Mailing-List: contact common-dev-help@hadoop.apache.org; run by ezmlm
Precedence: bulk
Reply-To: common-dev@hadoop.apache.org
Received-SPF: pass (athena.apache.org: domain of saint.ack@gmail.com
 designates 209.85.216.48 as permitted sender)
MIME-Version: 1.0
Sender: saint.ack@gmail.com
In-Reply-To: 
 <CA+z3+9FPHrkLMxDCMhsNa=u+vEgade-OndDGYhkTx6J85yu0ug@mail.gmail.com>
References: <CAB0CB4A.8DB2%sbush@yahoo-inc.com>
	<CA+z3+9FPHrkLMxDCMhsNa=u+vEgade-OndDGYhkTx6J85yu0ug@mail.gmail.com>
Date: Tue, 4 Oct 2011 16:40:48 -0700
Message-ID: 
 <CADcMMgHeUns7uv340MWOgLzGEmKVq0bCHp_Eb16hi1Ah5a9KQQ@mail.gmail.com>
Subject: Re: 0.20.205.0 Release Candidate 1 Testing
From: Stack <stack@duboce.net>
To: common-dev@hadoop.apache.org
Content-Type: text/plain; charset=ISO-8859-1
Content-Transfer-Encoding: quoted-printable

On Tue, Oct 4, 2011 at 3:41 PM, Matt Foley <mfoley@hortonworks.com> wrote:
> I am going to spin an RC2 early tomorrow. =A0Does anyone have other issue=
s
> they consider critical for 205.0?

I've been playing with it.

Recovering the lease on an open file (An HBase WAL) the length is
always zero and I don't seem to be able to recover any edits from the
file we writing at time of the crash:

2011-10-04 21:17:04,486 DEBUG
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Splitting hlog
34 of 34: hdfs://sv4r11s38:7000/hbase/.logs/sv4r8s38,7003,1317760866490/sv4=
r8s38%3A7003.1317762914728,
length=3D0
2011-10-04 21:17:04,486 INFO org.apache.hadoop.hbase.util.FSUtils:
Recovering file
hdfs://sv4r11s38:7000/hbase/.logs/sv4r8s38,7003,1317760866490/sv4r8s38%3A70=
03.1317762914728
2011-10-04 21:17:05,487 INFO org.apache.hadoop.hbase.util.FSUtils:
Finished lease recover attempt for
hdfs://sv4r11s38:7000/hbase/.logs/sv4r8s38,7003,1317760866490/sv4r8s38%3A70=
03.1317762914728
2011-10-04 21:17:05,488 WARN
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: File
hdfs://sv4r11s38:7000/hbase/.logs/sv4r8s38,7003,1317760866490/sv4r8s38%3A70=
03.1317762914728
might be still open, length is 0

Its probably me misconfiguring 205 compared to 0.20-append.

I got some of these tooo though I'd just opened the file a few seconds earl=
ier:

2011-10-04 21:16:28,439 DEBUG
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Creating writer
path=3Dhdfs://sv4r11s38:7000/hbase/TestTable/62ff2cb514838519e5fa4282a8af4c=
35/recovered.edits/0000000000000008111
region=3D62ff2cb514838519e5fa4282a8af4c35

....

2011-10-04 21:17:06,883 ERROR
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Couldn't close
log at hdfs://sv4r11s38:7000/hbase/TestTable/62ff2cb514838519e5fa4282a8af4c=
35/recovered.edits/0000000000000008111
org.apache.hadoop.ipc.RemoteException:
org.apache.hadoop.hdfs.server.namenode.LeaseExpiredException: No lease
on /hbase/TestTable/62ff2cb514838519e5fa4282a8af4c35/recovered.edits/000000=
0000000008111
File does not exist. [Lease.  Holder:
DFSClient_hb_m_sv4r11s38:7001_1317760883384, pendingcreates: 3]
        at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkLease(F=
SNamesystem.java:1604)
        at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkLease(F=
SNamesystem.java:1595)
        at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.completeFile=
Internal(FSNamesystem.java:1650)
        at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.completeFile=
(FSNamesystem.java:1638)
        at org.apache.hadoop.hdfs.server.namenode.NameNode.complete(NameNod=
e.java:682)
        at sun.reflect.GeneratedMethodAccessor15.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethod=
AccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:597)
        at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:563)
        at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1388)
        at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1384)
        at java.security.AccessController.doPrivileged(Native Method)
        at javax.security.auth.Subject.doAs(Subject.java:396)
        at org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupIn=
formation.java:1059)
        at org.apache.hadoop.ipc.Server$Handler.run(Server.java:1382)

        at org.apache.hadoop.ipc.Client.call(Client.java:1066)
        at org.apache.hadoop.ipc.RPC$Invoker.invoke(RPC.java:225)
        at $Proxy6.complete(Unknown Source)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessor=
Impl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethod=
AccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:597)
        at org.apache.hadoop.io.retry.RetryInvocationHandler.invokeMethod(R=
etryInvocationHandler.java:82)
        at org.apache.hadoop.io.retry.RetryInvocationHandler.invoke(RetryIn=
vocationHandler.java:59)
        at $Proxy6.complete(Unknown Source)
        at org.apache.hadoop.hdfs.DFSClient$DFSOutputStream.closeInternal(D=
FSClient.java:3711)
        at org.apache.hadoop.hdfs.DFSClient$DFSOutputStream.close(DFSClient=
.java:3626)
        at org.apache.hadoop.fs.FSDataOutputStream$PositionCache.close(FSDa=
taOutputStream.java:61)
        at org.apache.hadoop.fs.FSDataOutputStream.close(FSDataOutputStream=
.java:86)
        at org.apache.hadoop.io.SequenceFile$Writer.close(SequenceFile.java=
:966)
        at org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogWriter.c=
lose(SequenceFileLogWriter.java:138)
        at org.apache.hadoop.hbase.regionserver.wal.HLogSplitter$OutputSink=
.closeStreams(HLogSplitter.java:768)
        at org.apache.hadoop.hbase.regionserver.wal.HLogSplitter$OutputSink=
.finishWritingAndClose(HLogSplitter.java:753)
        at org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.splitLog(H=
LogSplitter.java:300)
        at org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.splitLog(H=
LogSplitter.java:188)
        at org.apache.hadoop.hbase.master.MasterFileSystem.splitLog(MasterF=
ileSystem.java:201)
        at org.apache.hadoop.hbase.master.handler.ServerShutdownHandler.pro=
cess(ServerShutdownHandler.java:153)
        at org.apache.hadoop.hbase.executor.EventHandler.run(EventHandler.j=
ava:156)
        at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoo=
lExecutor.java:886)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe=
cutor.java:908)
        at java.lang.Thread.run(Thread.java:662)

I'll keep banging at it.

St.Ack