Return-Path: X-Original-To: apmail-hbase-user-archive@www.apache.org Delivered-To: apmail-hbase-user-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 72CAC77D4 for ; Thu, 20 Oct 2011 18:15:46 +0000 (UTC) Received: (qmail 29746 invoked by uid 500); 20 Oct 2011 18:15:44 -0000 Delivered-To: apmail-hbase-user-archive@hbase.apache.org Received: (qmail 29715 invoked by uid 500); 20 Oct 2011 18:15:44 -0000 Mailing-List: contact user-help@hbase.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: user@hbase.apache.org Delivered-To: mailing list user@hbase.apache.org Received: (qmail 29702 invoked by uid 99); 20 Oct 2011 18:15:44 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 20 Oct 2011 18:15:44 +0000 X-ASF-Spam-Status: No, hits=1.1 required=5.0 tests=FREEMAIL_ENVFROM_END_DIGIT,FREEMAIL_FROM,FREEMAIL_REPLYTO_END_DIGIT,RCVD_IN_DNSWL_NONE,SPF_PASS X-Spam-Check-By: apache.org Received-SPF: pass (athena.apache.org: local policy) Received: from [72.30.238.75] (HELO nm35-vm3.bullet.mail.bf1.yahoo.com) (72.30.238.75) by apache.org (qpsmtpd/0.29) with SMTP; Thu, 20 Oct 2011 18:15:35 +0000 Received: from [98.139.212.150] by nm35.bullet.mail.bf1.yahoo.com with NNFMP; 20 Oct 2011 18:15:14 -0000 Received: from [98.139.212.197] by tm7.bullet.mail.bf1.yahoo.com with NNFMP; 20 Oct 2011 18:15:14 -0000 Received: from [127.0.0.1] by omp1006.mail.bf1.yahoo.com with NNFMP; 20 Oct 2011 18:15:14 -0000 X-Yahoo-Newman-Property: ymail-3 X-Yahoo-Newman-Id: 152185.19094.bm@omp1006.mail.bf1.yahoo.com Received: (qmail 29904 invoked by uid 60001); 20 Oct 2011 18:15:13 -0000 DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=yahoo.com; s=s1024; t=1319134513; bh=wym4xkABsXpvtWE1vZkI6h38t9zIUIUWYHCSea3eM4M=; h=X-YMail-OSG:Received:X-Mailer:References:Message-ID:Date:From:Reply-To:Subject:To:In-Reply-To:MIME-Version:Content-Type:Content-Transfer-Encoding; b=lToNbl0yNQxRkMeLhT3mFVjVOd3zY0poIvshu/B1b1AUaZ+x83Mza6suhmkZ2ricqVz1DJC5480JjeKBBkeOZw9REnjk2uERrM65SI8Ts+MfJQR7/3veUtIX+Llj8TT1BuXZmi3E4evbpNZdxRBxblYHh968HXv7K/75H4TMSjs= DomainKey-Signature: a=rsa-sha1; q=dns; c=nofws; s=s1024; d=yahoo.com; h=X-YMail-OSG:Received:X-Mailer:References:Message-ID:Date:From:Reply-To:Subject:To:In-Reply-To:MIME-Version:Content-Type:Content-Transfer-Encoding; b=cTZcUO2ewv+aNzMhvsHaYNM34wMPqY7O47zEz9PcnNRNmIWOJ3P0S4CnXNVRcckcSn8IgyMVibQqUb8dJXs1Gpcrs2CGOtfYlAIitXBd9LAA9bkdAzDp5rSuL1jqaOd0ONzQc6e2ELXw06iKMN/DUrpdFqdQbJXAJvFDtEbJvTM=; X-YMail-OSG: B.4AMS0VM1nivGHyagrMI0Eo9q.8KAFfs4qztBHNzDZH_sd U6eVfnRHX4XC3HsxeMqRD.vDZGGyVl4ncsLelQuFaFzpd1TVGmKFPpF4eeHn 27YgSeZGgH8b_ymlhFiACQwGuNs3c_cTZP0xjG3Bq93IyXd14EKZnr9hoSH2 mPtftHWhzSvh1pp9x6Jp0FZXB_FppdUXLNowqfD3wmJhe18aQMfjJl2PTGPu d1lgmOGBB9JkGVklJg8c_7AoipfdzpRpsmw9ywiQBrj0a6r7yJ2wKWxn_kzI KG8aygBNUvigmLNN7rjjRlNVVlyO2zr1pZ8.cCcj3o0XY1gNd.Xsk5n0sO.T GKD6QcLhJCzl5ZfrvT16m_RQ7jl10k8.wJaC.sXO4khohKjsd0uvOdvkZvyZ WwUWYw0uH3GubIOuZiSvxPWWbK3eUG0KDaQpc9mNKBPoX0ANJnXfp3kn5kxM JuF0Aab3I0BjrI51VoDopHNVC_A932__H1EQ9qgafyZu1AF9gUPee3IYdlCS 4DGnFSZwOeb6m1dxSGDMQFfLGLDERHJP.wsnHT4uDBDQWttIiXbvKZgN8AGQ 8Bq4v1HwLf1CIx1LgzZPXNBeyAQEuRZ88prlKy6uEVdCzPyI6jeBFxR57VTJ dronOj_UnvxL0ilEn1QcibO2wKgx7vnaZiEJj_aXT0jg8c2aVzNoazY_wKTi F1ic- Received: from [199.204.56.15] by web160717.mail.bf1.yahoo.com via HTTP; Thu, 20 Oct 2011 11:15:13 PDT X-Mailer: YahooMailWebService/0.8.114.317681 References: <1318819184.92688.YahooMailNeo@web65505.mail.ac4.yahoo.com> <1318855450.78740.YahooMailNeo@web65510.mail.ac4.yahoo.com> <1318947547.22081.YahooMailNeo@web160707.mail.bf1.yahoo.com> Message-ID: <1319134513.29398.YahooMailNeo@web160717.mail.bf1.yahoo.com> Date: Thu, 20 Oct 2011 11:15:13 -0700 (PDT) From: Ben West Reply-To: Ben West Subject: Re: HMaster issues To: "user@hbase.apache.org" In-Reply-To: MIME-Version: 1.0 Content-Type: text/plain; charset=iso-8859-1 Content-Transfer-Encoding: quoted-printable You're right:=0A=0A11/10/18 15:39:54 INFO zookeeper.ZooKeeper: Initiating c= lient connection, connectString=3Dlocalhost:2181 sessionTimeout=3D180000 = =0Awatcher=3Dhconnection=0A=0A=0AHow can I configure the port? It's set in = my hbase-site.xml file correctly.=0A=0A=0A=0A----- Original Message -----= =0AFrom: Jean-Daniel Cryans =0ATo: user@hbase.apache.o= rg; Ben West =0ACc: =0ASent: Tuesday, October 18, = 2011 1:23 PM=0ASubject: Re: HMaster issues=0A=0AThis line:=0A=0Ajava.lang.N= oSuchMethodException:=0Aorg.apache.hadoop.fs.ChecksumFileSystem$ChecksumFSI= nputChecker.getFileLength()=0A=0AIs because it's using the local filesystem= and not HDFS (so it's expected).=0A=0AAs far as I can tell the master star= ts ok, maybe the client is trying to use=0Athe ZK port 2181? Start the shel= l with -d and it should show you where it's=0Aconnecting.=0A=0AJ-D=0A=0AOn = Tue, Oct 18, 2011 at 7:19 AM, Ben West wrote:=0A= =0A> Hey All,=0A>=0A> I'm having an issue I can't debug. The shell gives me= a=0A> MasterNotRunningException, but I can connect to the master via the w= eb=0A> interface. I'm using the Cloudera demo VM, which has a standalone in= stance=0A> of HBase.=0A>=0A> I'm attaching the HBase-master log file. The o= nly thing I can see is a=0A> warning that getting a checksum failed, but fr= om the warning that doesn't=0A> sound very serious.=0A>=0A> The only thing = in my config file is to change the Zookeeper port since=0A> there seemed to= be a conflict with the default.=0A>=0A> Any help would be appreciated,=0A>= -Ben=0A>=0A>=0A> Tue Oct 18 08:27:17 EDT 2011 Starting master on localhost= .localdomain=0A> ulimit -n 1024=0A> 2011-10-18 08:27:20,631 INFO org.apache= .zookeeper.server.ZooKeeperServer:=0A> Server environment:zookeeper.version= =3D3.3.3-cdh3u1--1, built on 07/30/2011=0A> 01:29 GMT=0A> 2011-10-18 08:27:= 20,631 INFO org.apache.zookeeper.server.ZooKeeperServer:=0A> Server environ= ment:host.name=3Dlocalhost.localdomain=0A> 2011-10-18 08:27:20,631 INFO org= .apache.zookeeper.server.ZooKeeperServer:=0A> Server environment:java.versi= on=3D1.6.0_21=0A> 2011-10-18 08:27:20,631 INFO org.apache.zookeeper.server.= ZooKeeperServer:=0A> Server environment:java.vendor=3DSun Microsystems Inc.= =0A> 2011-10-18 08:27:20,631 INFO org.apache.zookeeper.server.ZooKeeperServ= er:=0A> Server environment:java.home=3D/usr/java/jdk1.6.0_21/jre=0A> 2011-1= 0-18 08:27:20,631 INFO org.apache.zookeeper.server.ZooKeeperServer:=0A> Ser= ver=0A>=0A>=A0 environment:java.class.path=3D/usr/lib/hbase/bin/../conf:/usr/java/jdk1.6.= 0_21/lib/tools.jar:/usr/lib/hbase/bin/..:/usr/lib/hbase/bin/../hbase-0.90.3= -cdh3u1.jar:/usr/lib/hbase/bin/../hbase-0.90.3-cdh3u1-tests.jar:/usr/lib/hb= ase/bin/../lib/activation-1.1.jar:/usr/lib/hbase/bin/../lib/asm-3.1.jar:/us= r/lib/hbase/bin/../lib/avro-1.3.3.jar:/usr/lib/hbase/bin/../lib/commons-cli= -1.2.jar:/usr/lib/hbase/bin/../lib/commons-codec-1.4.jar:/usr/lib/hbase/bin= /../lib/commons-el-1.0.jar:/usr/lib/hbase/bin/../lib/commons-httpclient-3.1= .jar:/usr/lib/hbase/bin/../lib/commons-lang-2.5.jar:/usr/lib/hbase/bin/../l= ib/commons-logging-1.1.1.jar:/usr/lib/hbase/bin/../lib/commons-net-1.4.1.ja= r:/usr/lib/hbase/bin/../lib/core-3.1.1.jar:/usr/lib/hbase/bin/../lib/guava-= r06.jar:/usr/lib/hbase/bin/../lib/hadoop-core.jar:/usr/lib/hbase/bin/../lib= /jackson-core-asl-1.5.2.jar:/usr/lib/hbase/bin/../lib/jackson-jaxrs-1.5.5.j= ar:/usr/lib/hbase/bin/../lib/jackson-mapper-asl-1.5.2.jar:/usr/lib/hb=0A>= =0A> ase/bin/../lib/jackson-xc-1.5.5.jar:/usr/lib/hbase/bin/../lib/jasper-c= ompiler-5.5.23.jar:/usr/lib/hbase/bin/../lib/jasper-runtime-5.5.23.jar:/usr= /lib/hbase/bin/../lib/jaxb-api-2.1.jar:/usr/lib/hbase/bin/../lib/jaxb-impl-= 2.1.12.jar:/usr/lib/hbase/bin/../lib/jersey-core-1.4.jar:/usr/lib/hbase/bin= /../lib/jersey-json-1.4.jar:/usr/lib/hbase/bin/../lib/jersey-server-1.4.jar= :/usr/lib/hbase/bin/../lib/jettison-1.1.jar:/usr/lib/hbase/bin/../lib/jetty= -6.1.26.jar:/usr/lib/hbase/bin/../lib/jetty-util-6.1.26.jar:/usr/lib/hbase/= bin/../lib/jruby-complete-1.6.0.jar:/usr/lib/hbase/bin/../lib/jsp-2.1-6.1.1= 4.jar:/usr/lib/hbase/bin/../lib/jsp-api-2.1-6.1.14.jar:/usr/lib/hbase/bin/.= ./lib/jsp-api-2.1.jar:/usr/lib/hbase/bin/../lib/jsr311-api-1.1.1.jar:/usr/l= ib/hbase/bin/../lib/log4j-1.2.16.jar:/usr/lib/hbase/bin/../lib/protobuf-jav= a-2.3.0.jar:/usr/lib/hbase/bin/../lib/servlet-api-2.5-6.1.14.jar:/usr/lib/h= base/bin/../lib/servlet-api-2.5.jar:/usr/lib/hbase/bin/../lib/slf4j-api-=0A= >=0A> 1.5.8.jar:/usr/lib/hbase/bin/../lib/slf4j-log4j12-1.5.8.jar:/usr/lib/= hbase/bin/../lib/stax-api-1.0.1.jar:/usr/lib/hbase/bin/../lib/thrift-0.2.0.= jar:/usr/lib/hbase/bin/../lib/xmlenc-0.52.jar:/usr/lib/hbase/bin/../lib/zoo= keeper.jar=0A> 2011-10-18 08:27:20,631 INFO org.apache.zookeeper.server.Zoo= KeeperServer:=0A> Server=0A> environment:java.library.path=3D/usr/lib/hbase= /bin/../lib/native/Linux-amd64-64=0A> 2011-10-18 08:27:20,631 INFO org.apac= he.zookeeper.server.ZooKeeperServer:=0A> Server environment:java.io.tmpdir= =3D/tmp=0A> 2011-10-18 08:27:20,631 INFO org.apache.zookeeper.server.ZooKee= perServer:=0A> Server environment:java.compiler=3D=0A> 2011-10-18 08:27= :20,631 INFO org.apache.zookeeper.server.ZooKeeperServer:=0A> Server enviro= nment:os.name=3DLinux=0A> 2011-10-18 08:27:20,631 INFO org.apache.zookeeper= .server.ZooKeeperServer:=0A> Server environment:os.arch=3Damd64=0A> 2011-10= -18 08:27:20,631 INFO org.apache.zookeeper.server.ZooKeeperServer:=0A> Serv= er environment:os.version=3D2.6.18-238.19.1.el5=0A> 2011-10-18 08:27:20,631= INFO org.apache.zookeeper.server.ZooKeeperServer:=0A> Server environment:u= ser.name=3Dhbase=0A> 2011-10-18 08:27:20,631 INFO org.apache.zookeeper.serv= er.ZooKeeperServer:=0A> Server environment:user.home=3D/var/run/hbase=0A> 2= 011-10-18 08:27:20,631 INFO org.apache.zookeeper.server.ZooKeeperServer:=0A= > Server environment:user.dir=3D/=0A> 2011-10-18 08:27:20,837 INFO org.apac= he.zookeeper.server.ZooKeeperServer:=0A> Created server with tickTime 2000 = minSessionTimeout 4000 maxSessionTimeout=0A> 40000 datadir /tmp/hbase-hbase= /zookeeper/zookeeper/version-2 snapdir=0A> /tmp/hbase-hbase/zookeeper/zooke= eper/version-2=0A> 2011-10-18 08:27:21,189 INFO org.apache.zookeeper.server= .NIOServerCnxn:=0A> binding to port 0.0.0.0/0.0.0.0:2182=0A> 2011-10-18 08:= 27:21,220 INFO=0A> org.apache.zookeeper.server.persistence.FileTxnSnapLog: = Snapshotting: 0=0A> 2011-10-18 08:27:21,463 INFO org.apache.zookeeper.serve= r.NIOServerCnxn:=0A> Accepted socket connection from /127.0.0.1:48821=0A> 2= 011-10-18 08:27:21,485 INFO org.apache.zookeeper.server.NIOServerCnxn:=0A> = Processing stat command from /127.0.0.1:48821=0A> 2011-10-18 08:27:21,488 I= NFO org.apache.zookeeper.server.NIOServerCnxn:=0A> Stat command output=0A> = 2011-10-18 08:27:21,488 INFO org.apache.zookeeper.server.NIOServerCnxn:=0A>= Closed socket connection for client /127.0.0.1:48821 (no session=0A> estab= lished for client)=0A> 2011-10-18 08:27:21,489 INFO=0A> org.apache.hadoop.h= base.zookeeper.MiniZooKeeperCluster: Started MiniZK=0A> Server on client po= rt: 2182=0A> 2011-10-18 08:27:21,939 INFO org.apache.hadoop.hbase.ipc.HBase= RpcMetrics:=0A> Initializing RPC Metrics with hostName=3DHMasterCommandLine= $LocalHMaster,=0A> port=3D49977=0A> 2011-10-18 08:27:23,849 INFO org.apache= .hadoop.ipc.HBaseServer: IPC Server=0A> Responder: starting=0A> 2011-10-18 = 08:27:23,870 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server=0A> listene= r on 49977: starting=0A> 2011-10-18 08:27:23,884 INFO org.apache.hadoop.ipc= .HBaseServer: IPC Server=0A> handler 0 on 49977: starting=0A> 2011-10-18 08= :27:23,885 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server=0A> handler 1= on 49977: starting=0A> 2011-10-18 08:27:23,909 INFO org.apache.hadoop.ipc.= HBaseServer: IPC Server=0A> handler 3 on 49977: starting=0A> 2011-10-18 08:= 27:23,909 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server=0A> handler 2 = on 49977: starting=0A> 2011-10-18 08:27:23,910 INFO org.apache.hadoop.ipc.H= BaseServer: IPC Server=0A> handler 4 on 49977: starting=0A> 2011-10-18 08:2= 7:23,910 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server=0A> handler 5 o= n 49977: starting=0A> 2011-10-18 08:27:23,910 INFO org.apache.hadoop.ipc.HB= aseServer: IPC Server=0A> handler 6 on 49977: starting=0A> 2011-10-18 08:27= :23,911 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server=0A> handler 7 on= 49977: starting=0A> 2011-10-18 08:27:23,911 INFO org.apache.hadoop.ipc.HBa= seServer: IPC Server=0A> handler 8 on 49977: starting=0A> 2011-10-18 08:27:= 23,911 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server=0A> handler 9 on = 49977: starting=0A> 2011-10-18 08:27:23,951 INFO org.apache.zookeeper.ZooKe= eper: Client=0A> environment:zookeeper.version=3D3.3.3-cdh3u1--1, built on = 07/30/2011 01:29 GMT=0A> 2011-10-18 08:27:23,951 INFO org.apache.zookeeper.= ZooKeeper: Client=0A> environment:host.name=3Dlocalhost.localdomain=0A> 201= 1-10-18 08:27:23,951 INFO org.apache.zookeeper.ZooKeeper: Client=0A> enviro= nment:java.version=3D1.6.0_21=0A> 2011-10-18 08:27:23,951 INFO org.apache.z= ookeeper.ZooKeeper: Client=0A> environment:java.vendor=3DSun Microsystems I= nc.=0A> 2011-10-18 08:27:23,951 INFO org.apache.zookeeper.ZooKeeper: Client= =0A> environment:java.home=3D/usr/java/jdk1.6.0_21/jre=0A> 2011-10-18 08:27= :23,951 INFO org.apache.zookeeper.ZooKeeper: Client=0A>=0A>=A0 environment:java.class.path=3D/usr/lib/hbase/bin/../conf:/usr/java/jdk1.6.= 0_21/lib/tools.jar:/usr/lib/hbase/bin/..:/usr/lib/hbase/bin/../hbase-0.90.3= -cdh3u1.jar:/usr/lib/hbase/bin/../hbase-0.90.3-cdh3u1-tests.jar:/usr/lib/hb= ase/bin/../lib/activation-1.1.jar:/usr/lib/hbase/bin/../lib/asm-3.1.jar:/us= r/lib/hbase/bin/../lib/avro-1.3.3.jar:/usr/lib/hbase/bin/../lib/commons-cli= -1.2.jar:/usr/lib/hbase/bin/../lib/commons-codec-1.4.jar:/usr/lib/hbase/bin= /../lib/commons-el-1.0.jar:/usr/lib/hbase/bin/../lib/commons-httpclient-3.1= .jar:/usr/lib/hbase/bin/../lib/commons-lang-2.5.jar:/usr/lib/hbase/bin/../l= ib/commons-logging-1.1.1.jar:/usr/lib/hbase/bin/../lib/commons-net-1.4.1.ja= r:/usr/lib/hbase/bin/../lib/core-3.1.1.jar:/usr/lib/hbase/bin/../lib/guava-= r06.jar:/usr/lib/hbase/bin/../lib/hadoop-core.jar:/usr/lib/hbase/bin/../lib= /jackson-core-asl-1.5.2.jar:/usr/lib/hbase/bin/../lib/jackson-jaxrs-1.5.5.j= ar:/usr/lib/hbase/bin/../lib/jackson-mapper-asl-1.5.2.jar:/usr/lib/hb=0A>= =0A> ase/bin/../lib/jackson-xc-1.5.5.jar:/usr/lib/hbase/bin/../lib/jasper-c= ompiler-5.5.23.jar:/usr/lib/hbase/bin/../lib/jasper-runtime-5.5.23.jar:/usr= /lib/hbase/bin/../lib/jaxb-api-2.1.jar:/usr/lib/hbase/bin/../lib/jaxb-impl-= 2.1.12.jar:/usr/lib/hbase/bin/../lib/jersey-core-1.4.jar:/usr/lib/hbase/bin= /../lib/jersey-json-1.4.jar:/usr/lib/hbase/bin/../lib/jersey-server-1.4.jar= :/usr/lib/hbase/bin/../lib/jettison-1.1.jar:/usr/lib/hbase/bin/../lib/jetty= -6.1.26.jar:/usr/lib/hbase/bin/../lib/jetty-util-6.1.26.jar:/usr/lib/hbase/= bin/../lib/jruby-complete-1.6.0.jar:/usr/lib/hbase/bin/../lib/jsp-2.1-6.1.1= 4.jar:/usr/lib/hbase/bin/../lib/jsp-api-2.1-6.1.14.jar:/usr/lib/hbase/bin/.= ./lib/jsp-api-2.1.jar:/usr/lib/hbase/bin/../lib/jsr311-api-1.1.1.jar:/usr/l= ib/hbase/bin/../lib/log4j-1.2.16.jar:/usr/lib/hbase/bin/../lib/protobuf-jav= a-2.3.0.jar:/usr/lib/hbase/bin/../lib/servlet-api-2.5-6.1.14.jar:/usr/lib/h= base/bin/../lib/servlet-api-2.5.jar:/usr/lib/hbase/bin/../lib/slf4j-api-=0A= >=0A> 1.5.8.jar:/usr/lib/hbase/bin/../lib/slf4j-log4j12-1.5.8.jar:/usr/lib/= hbase/bin/../lib/stax-api-1.0.1.jar:/usr/lib/hbase/bin/../lib/thrift-0.2.0.= jar:/usr/lib/hbase/bin/../lib/xmlenc-0.52.jar:/usr/lib/hbase/bin/../lib/zoo= keeper.jar=0A> 2011-10-18 08:27:23,951 INFO org.apache.zookeeper.ZooKeeper:= Client=0A> environment:java.library.path=3D/usr/lib/hbase/bin/../lib/nativ= e/Linux-amd64-64=0A> 2011-10-18 08:27:23,951 INFO org.apache.zookeeper.ZooK= eeper: Client=0A> environment:java.io.tmpdir=3D/tmp=0A> 2011-10-18 08:27:23= ,951 INFO org.apache.zookeeper.ZooKeeper: Client=0A> environment:java.compi= ler=3D=0A> 2011-10-18 08:27:23,951 INFO org.apache.zookeeper.ZooKeeper:= Client=0A> environment:os.name=3DLinux=0A> 2011-10-18 08:27:23,951 INFO or= g.apache.zookeeper.ZooKeeper: Client=0A> environment:os.arch=3Damd64=0A> 20= 11-10-18 08:27:23,951 INFO org.apache.zookeeper.ZooKeeper: Client=0A> envir= onment:os.version=3D2.6.18-238.19.1.el5=0A> 2011-10-18 08:27:23,951 INFO or= g.apache.zookeeper.ZooKeeper: Client=0A> environment:user.name=3Dhbase=0A> = 2011-10-18 08:27:23,951 INFO org.apache.zookeeper.ZooKeeper: Client=0A> env= ironment:user.home=3D/var/run/hbase=0A> 2011-10-18 08:27:23,951 INFO org.ap= ache.zookeeper.ZooKeeper: Client=0A> environment:user.dir=3D/=0A> 2011-10-1= 8 08:27:23,952 INFO org.apache.zookeeper.ZooKeeper: Initiating=0A> client c= onnection, connectString=3Dlocalhost:2182 sessionTimeout=3D180000=0A> watch= er=3Dmaster:49977=0A> 2011-10-18 08:27:24,009 INFO org.apache.zookeeper.Cli= entCnxn: Opening=0A> socket connection to server localhost/127.0.0.1:2182= =0A> 2011-10-18 08:27:24,047 INFO org.apache.zookeeper.server.NIOServerCnxn= :=0A> Accepted socket connection from /127.0.0.1:48824=0A> 2011-10-18 08:27= :24,047 INFO org.apache.zookeeper.ClientCnxn: Socket=0A> connection establi= shed to localhost/127.0.0.1:2182, initiating session=0A> 2011-10-18 08:27:2= 4,064 INFO org.apache.zookeeper.server.NIOServerCnxn:=0A> Client attempting= to establish new session at /127.0.0.1:48824=0A> 2011-10-18 08:27:24,066 I= NFO=0A> org.apache.zookeeper.server.persistence.FileTxnLog: Creating new lo= g file:=0A> log.1=0A> 2011-10-18 08:27:24,092 INFO org.apache.zookeeper.ser= ver.NIOServerCnxn:=0A> Established session 0x1331700150e0000 with negotiate= d timeout 40000 for=0A> client /127.0.0.1:48824=0A> 2011-10-18 08:27:24,097= INFO org.apache.zookeeper.ClientCnxn: Session=0A> establishment complete o= n server localhost/127.0.0.1:2182, sessionid =3D=0A> 0x1331700150e0000, neg= otiated timeout =3D 40000=0A> 2011-10-18 08:27:24,111 INFO org.apache.hadoo= p.metrics.jvm.JvmMetrics:=0A> Initializing JVM Metrics with processName=3DM= aster,=0A> sessionId=3Dlocalhost.localdomain:49977=0A> 2011-10-18 08:27:24,= 131 INFO org.apache.hadoop.hbase.metrics: MetricsString=0A> added: revision= =0A> 2011-10-18 08:27:24,131 INFO org.apache.hadoop.hbase.metrics: MetricsS= tring=0A> added: hdfsUser=0A> 2011-10-18 08:27:24,131 INFO org.apache.hadoo= p.hbase.metrics: MetricsString=0A> added: hdfsDate=0A> 2011-10-18 08:27:24,= 131 INFO org.apache.hadoop.hbase.metrics: MetricsString=0A> added: hdfsUrl= =0A> 2011-10-18 08:27:24,131 INFO org.apache.hadoop.hbase.metrics: MetricsS= tring=0A> added: date=0A> 2011-10-18 08:27:24,131 INFO org.apache.hadoop.hb= ase.metrics: MetricsString=0A> added: hdfsRevision=0A> 2011-10-18 08:27:24,= 131 INFO org.apache.hadoop.hbase.metrics: MetricsString=0A> added: user=0A>= 2011-10-18 08:27:24,131 INFO org.apache.hadoop.hbase.metrics: MetricsStrin= g=0A> added: hdfsVersion=0A> 2011-10-18 08:27:24,131 INFO org.apache.hadoop= .hbase.metrics: MetricsString=0A> added: url=0A> 2011-10-18 08:27:24,131 IN= FO org.apache.hadoop.hbase.metrics: MetricsString=0A> added: version=0A> 20= 11-10-18 08:27:24,131 INFO org.apache.hadoop.hbase.metrics: new MBeanInfo= =0A> 2011-10-18 08:27:24,131 INFO org.apache.hadoop.hbase.metrics: new MBea= nInfo=0A> 2011-10-18 08:27:24,132 INFO=0A> org.apache.hadoop.hbase.master.m= etrics.MasterMetrics: Initialized=0A> 2011-10-18 08:27:24,508 INFO org.apac= he.zookeeper.ZooKeeper: Initiating=0A> client connection, connectString=3Dl= ocalhost:2182 sessionTimeout=3D180000=0A> watcher=3Dhconnection=0A> 2011-10= -18 08:27:24,508 INFO org.apache.zookeeper.ClientCnxn: Opening=0A> socket c= onnection to server localhost/127.0.0.1:2182=0A> 2011-10-18 08:27:24,527 IN= FO org.apache.zookeeper.server.NIOServerCnxn:=0A> Accepted socket connectio= n from /127.0.0.1:48825=0A> 2011-10-18 08:27:24,528 INFO org.apache.zookeep= er.ClientCnxn: Socket=0A> connection established to localhost/127.0.0.1:218= 2, initiating session=0A> 2011-10-18 08:27:24,528 INFO org.apache.zookeeper= .server.NIOServerCnxn:=0A> Client attempting to establish new session at /1= 27.0.0.1:48825=0A> 2011-10-18 08:27:24,537 INFO org.apache.zookeeper.server= .NIOServerCnxn:=0A> Established session 0x1331700150e0001 with negotiated t= imeout 40000 for=0A> client /127.0.0.1:48825=0A> 2011-10-18 08:27:24,539 IN= FO org.apache.zookeeper.ClientCnxn: Session=0A> establishment complete on s= erver localhost/127.0.0.1:2182, sessionid =3D=0A> 0x1331700150e0001, negoti= ated timeout =3D 40000=0A> 2011-10-18 08:27:24,727 INFO org.apache.hadoop.h= base.ipc.HBaseRpcMetrics:=0A> Initializing RPC Metrics with hostName=3DHReg= ionServer, port=3D57754=0A> 2011-10-18 08:27:25,093 INFO org.apache.zookeep= er.ZooKeeper: Initiating=0A> client connection, connectString=3Dlocalhost:2= 182 sessionTimeout=3D180000=0A> watcher=3Dregionserver:57754=0A> 2011-10-18= 08:27:25,093 INFO=0A> org.apache.hadoop.hbase.master.ActiveMasterManager:= =0A> Master=3Dlocalhost.localdomain:49977=0A> 2011-10-18 08:27:25,107 INFO = org.apache.zookeeper.ClientCnxn: Opening=0A> socket connection to server lo= calhost/127.0.0.1:2182=0A> 2011-10-18 08:27:25,107 INFO org.apache.zookeepe= r.server.NIOServerCnxn:=0A> Accepted socket connection from /127.0.0.1:4882= 6=0A> 2011-10-18 08:27:25,109 INFO org.apache.zookeeper.ClientCnxn: Socket= =0A> connection established to localhost/127.0.0.1:2182, initiating session= =0A> 2011-10-18 08:27:25,109 INFO org.apache.zookeeper.server.NIOServerCnxn= :=0A> Client attempting to establish new session at /127.0.0.1:48826=0A> 20= 11-10-18 08:27:25,113 INFO org.apache.zookeeper.server.NIOServerCnxn:=0A> E= stablished session 0x1331700150e0002 with negotiated timeout 40000 for=0A> = client /127.0.0.1:48826=0A> 2011-10-18 08:27:25,117 INFO org.apache.zookeep= er.ClientCnxn: Session=0A> establishment complete on server localhost/127.0= .0.1:2182, sessionid =3D=0A> 0x1331700150e0002, negotiated timeout =3D 4000= 0=0A> 2011-10-18 08:27:26,071 INFO org.apache.zookeeper.ZooKeeper: Initiati= ng=0A> client connection, connectString=3Dlocalhost:2182 sessionTimeout=3D1= 80000=0A> watcher=3Dhconnection=0A> 2011-10-18 08:27:26,072 INFO org.apache= .zookeeper.ClientCnxn: Opening=0A> socket connection to server localhost/12= 7.0.0.1:2182=0A> 2011-10-18 08:27:26,072 INFO org.apache.zookeeper.server.N= IOServerCnxn:=0A> Accepted socket connection from /127.0.0.1:48827=0A> 2011= -10-18 08:27:26,072 INFO org.apache.zookeeper.ClientCnxn: Socket=0A> connec= tion established to localhost/127.0.0.1:2182, initiating session=0A> 2011-1= 0-18 08:27:26,073 INFO org.apache.zookeeper.server.NIOServerCnxn:=0A> Clien= t attempting to establish new session at /127.0.0.1:48827=0A> 2011-10-18 08= :27:26,078 INFO org.apache.zookeeper.server.NIOServerCnxn:=0A> Established = session 0x1331700150e0003 with negotiated timeout 40000 for=0A> client /127= .0.0.1:48827=0A> 2011-10-18 08:27:26,080 INFO org.apache.zookeeper.ClientCn= xn: Session=0A> establishment complete on server localhost/127.0.0.1:2182, = sessionid =3D=0A> 0x1331700150e0003, negotiated timeout =3D 40000=0A> 2011-= 10-18 08:27:26,148 DEBUG=0A> org.apache.hadoop.hbase.catalog.CatalogTracker= : Starting catalog tracker=0A> org.apache.hadoop.hbase.catalog.CatalogTrack= er@7f5d3388=0A> 2011-10-18 08:27:26,212 DEBUG=0A> org.apache.hadoop.hbase.c= atalog.CatalogTracker: Starting catalog tracker=0A> org.apache.hadoop.hbase= .catalog.CatalogTracker@342798e7=0A> 2011-10-18 08:27:26,217 INFO=0A> org.a= pache.hadoop.hbase.regionserver.MemStoreFlusher:=0A> globalMemStoreLimit=3D= 399.2m, globalMemStoreLimitLowMark=3D349.3m,=0A> maxHeap=3D997.9m=0A> 2011-= 10-18 08:27:26,219 DEBUG=0A> org.apache.hadoop.hbase.regionserver.PriorityC= ompactionQueue: Create=0A> PriorityCompactionQueue=0A> 2011-10-18 08:27:26,= 219 INFO=0A> org.apache.hadoop.hbase.regionserver.HRegionServer: Runs every= 10000000ms=0A> 2011-10-18 08:27:26,294 INFO=0A> org.apache.hadoop.hbase.re= gionserver.HRegionServer: Attempting connect to=0A> Master server at localh= ost.localdomain:49977=0A> 2011-10-18 08:27:26,344 INFO=0A> org.apache.hadoo= p.hbase.regionserver.HRegionServer: Master isn't available=0A> yet, retryin= g=0A> 2011-10-18 08:27:26,348 INFO org.apache.hadoop.hbase.master.HMaster: = Server=0A> active/primary master; localhost.localdomain:49977,=0A> sessioni= d=3D0x1331700150e0000, cluster-up flag was=3Dfalse=0A> 2011-10-18 08:27:26,= 349 DEBUG=0A> org.apache.hadoop.hbase.executor.ExecutorService: Starting ex= ecutor service=0A> name=3DMASTER_OPEN_REGION-localhost.localdomain:49977, c= orePoolSize=3D5,=0A> maxPoolSize=3D5=0A> 2011-10-18 08:27:26,349 DEBUG=0A> = org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service= =0A> name=3DMASTER_CLOSE_REGION-localhost.localdomain:49977, corePoolSize= =3D5,=0A> maxPoolSize=3D5=0A> 2011-10-18 08:27:26,349 DEBUG=0A> org.apache.= hadoop.hbase.executor.ExecutorService: Starting executor service=0A> name= =3DMASTER_SERVER_OPERATIONS-localhost.localdomain:49977, corePoolSize=3D3,= =0A> maxPoolSize=3D3=0A> 2011-10-18 08:27:26,349 DEBUG=0A> org.apache.hadoo= p.hbase.executor.ExecutorService: Starting executor service=0A> name=3DMAST= ER_META_SERVER_OPERATIONS-localhost.localdomain:49977,=0A> corePoolSize=3D5= , maxPoolSize=3D5=0A> 2011-10-18 08:27:26,349 DEBUG=0A> org.apache.hadoop.h= base.executor.ExecutorService: Starting executor service=0A> name=3DMASTER_= TABLE_OPERATIONS-localhost.localdomain:49977, corePoolSize=3D1,=0A> maxPool= Size=3D1=0A> 2011-10-18 08:27:26,351 DEBUG org.apache.hadoop.hbase.master.L= ogCleaner:=0A> Add log cleaner in chain:=0A> org.apache.hadoop.hbase.master= .TimeToLiveLogCleaner=0A> 2011-10-18 08:27:26,739 INFO org.mortbay.log: Log= ging to=0A> org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via=0A> org.= mortbay.log.Slf4jLog=0A> 2011-10-18 08:27:26,832 INFO org.apache.hadoop.htt= p.HttpServer: Added=0A> global filtersafety=0A> (class=3Dorg.apache.hadoop.= http.HttpServer$QuotingInputFilter)=0A> 2011-10-18 08:27:26,838 INFO org.ap= ache.hadoop.http.HttpServer: Port=0A> returned by webServer.getConnectors()= [0].getLocalPort() before open() is -1.=0A> Opening the listener on 60010= =0A> 2011-10-18 08:27:26,838 INFO org.apache.hadoop.http.HttpServer:=0A> li= stener.getLocalPort() returned 60010=0A> webServer.getConnectors()[0].getLo= calPort() returned 60010=0A> 2011-10-18 08:27:26,838 INFO org.apache.hadoop= .http.HttpServer: Jetty bound=0A> to port 60010=0A> 2011-10-18 08:27:26,838= INFO org.mortbay.log: jetty-6.1.26=0A> 2011-10-18 08:27:27,019 WARN org.mo= rtbay.log: Can't reuse=0A> /tmp/Jetty_0_0_0_0_60010_master____q3nwom, using= =0A> /tmp/Jetty_0_0_0_0_60010_master____q3nwom_3288413844900815841=0A> 2011= -10-18 08:27:27,877 INFO org.mortbay.log: Started=0A> SelectChannelConnecto= r@0.0.0.0:60010=0A> 2011-10-18 08:27:27,877 DEBUG org.apache.hadoop.hbase.m= aster.HMaster:=0A> Started service threads=0A> 2011-10-18 08:27:29,348 INFO= =0A> org.apache.hadoop.hbase.regionserver.HRegionServer: Attempting connect= to=0A> Master server at localhost.localdomain:49977=0A> 2011-10-18 08:27:2= 9,356 INFO=0A> org.apache.hadoop.hbase.regionserver.HRegionServer: Connecte= d to master at=0A> localhost.localdomain:49977=0A> 2011-10-18 08:27:29,359 = INFO=0A> org.apache.hadoop.hbase.regionserver.HRegionServer: Telling master= at=0A> localhost.localdomain:49977 that we are up=0A> 2011-10-18 08:27:29,= 364 INFO org.apache.hadoop.hbase.master.ServerManager:=0A> Registering serv= er=3Dlocalhost.localdomain,57754,1318940844922, regionCount=3D0,=0A> userLo= ad=3Dfalse=0A> 2011-10-18 08:27:29,373 INFO=0A> org.apache.hadoop.hbase.reg= ionserver.HRegionServer: Master passed us address=0A> to use. Was=3Dlocalho= st.localdomain:57754, Now=3Dlocalhost.localdomain:57754=0A> 2011-10-18 08:2= 7:29,373 DEBUG=0A> org.apache.hadoop.hbase.regionserver.HRegionServer: Conf= ig from master:=0A> fs.default.name=3Dfile:///=0A> 2011-10-18 08:27:29,373 = DEBUG=0A> org.apache.hadoop.hbase.regionserver.HRegionServer: Config from m= aster:=0A> hbase.rootdir=3Dfile:///tmp/hbase-hbase/hbase=0A> 2011-10-18 08:= 27:29,376 DEBUG=0A> org.apache.hadoop.hbase.regionserver.HRegionServer:=0A>= logdir=3Dfile:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,57754,131= 8940844922=0A> 2011-10-18 08:27:29,380 INFO org.apache.hadoop.hbase.master.= ServerManager:=0A> Waiting on regionserver(s) count to settle; currently=3D= 1=0A> 2011-10-18 08:27:29,399 INFO org.apache.hadoop.hbase.regionserver.wal= .HLog:=0A> HLog configuration: blocksize=3D32 MB, rollsize=3D30.4 MB, enabl= ed=3Dtrue,=0A> flushlogentries=3D1, optionallogflushinternal=3D1000ms=0A> 2= 011-10-18 08:27:29,450 WARN org.apache.hadoop.util.NativeCodeLoader:=0A> Un= able to load native-hadoop library for your platform... using builtin-java= =0A> classes where applicable=0A> 2011-10-18 08:27:29,496 INFO=0A> org.apac= he.hadoop.hbase.regionserver.wal.SequenceFileLogWriter: syncFs --=0A> HDFS-= 200 -- not available, dfs.support.append=3Dfalse=0A> 2011-10-18 08:27:29,49= 7 INFO org.apache.hadoop.hbase.regionserver.wal.HLog:=0A> New hlog=0A> /tmp= /hbase-hbase/hbase/.logs/localhost.localdomain,57754,1318940844922/localhos= t.localdomain%3A57754.1318940849409=0A> 2011-10-18 08:27:29,497 INFO org.ap= ache.hadoop.hbase.regionserver.wal.HLog:=0A> getNumCurrentReplicas--HDFS-82= 6 not available;=0A> hdfs_out=3Dorg.apache.hadoop.fs.ChecksumFileSystem$Che= cksumFSOutputSummer@381eb0c6,=0A> exception=3Dorg.apache.hadoop.fs.Checksum= FileSystem$ChecksumFSOutputSummer.getNumCurrentReplicas()=0A> 2011-10-18 08= :27:29,506 INFO org.apache.hadoop.metrics.jvm.JvmMetrics:=0A> Cannot initia= lize JVM Metrics with processName=3DRegionServer,=0A> sessionId=3DRegionSer= ver:0;localhost.localdomain,57754,1318940844922 - already=0A> initialized= =0A> 2011-10-18 08:27:29,509 INFO org.apache.hadoop.hbase.metrics: new MBea= nInfo=0A> 2011-10-18 08:27:29,510 INFO=0A> org.apache.hadoop.hbase.regionse= rver.metrics.RegionServerMetrics:=0A> Initialized=0A> 2011-10-18 08:27:29,5= 11 DEBUG=0A> org.apache.hadoop.hbase.executor.ExecutorService: Starting exe= cutor service=0A> name=3DRS_OPEN_REGION-localhost.localdomain,57754,1318940= 844922,=0A> corePoolSize=3D3, maxPoolSize=3D3=0A> 2011-10-18 08:27:29,512 D= EBUG=0A> org.apache.hadoop.hbase.executor.ExecutorService: Starting executo= r service=0A> name=3DRS_OPEN_ROOT-localhost.localdomain,57754,1318940844922= , corePoolSize=3D1,=0A> maxPoolSize=3D1=0A> 2011-10-18 08:27:29,512 DEBUG= =0A> org.apache.hadoop.hbase.executor.ExecutorService: Starting executor se= rvice=0A> name=3DRS_OPEN_META-localhost.localdomain,57754,1318940844922, co= rePoolSize=3D1,=0A> maxPoolSize=3D1=0A> 2011-10-18 08:27:29,512 DEBUG=0A> o= rg.apache.hadoop.hbase.executor.ExecutorService: Starting executor service= =0A> name=3DRS_CLOSE_REGION-localhost.localdomain,57754,1318940844922,=0A> = corePoolSize=3D3, maxPoolSize=3D3=0A> 2011-10-18 08:27:29,512 DEBUG=0A> org= .apache.hadoop.hbase.executor.ExecutorService: Starting executor service=0A= > name=3DRS_CLOSE_ROOT-localhost.localdomain,57754,1318940844922,=0A> coreP= oolSize=3D1, maxPoolSize=3D1=0A> 2011-10-18 08:27:29,512 DEBUG=0A> org.apac= he.hadoop.hbase.executor.ExecutorService: Starting executor service=0A> nam= e=3DRS_CLOSE_META-localhost.localdomain,57754,1318940844922,=0A> corePoolSi= ze=3D1, maxPoolSize=3D1=0A> 2011-10-18 08:27:29,699 INFO org.apache.hadoop.= http.HttpServer: Added=0A> global filtersafety=0A> (class=3Dorg.apache.hado= op.http.HttpServer$QuotingInputFilter)=0A> 2011-10-18 08:27:29,700 INFO org= .apache.hadoop.http.HttpServer: Port=0A> returned by webServer.getConnector= s()[0].getLocalPort() before open() is -1.=0A> Opening the listener on 6003= 0=0A> 2011-10-18 08:27:29,700 INFO org.apache.hadoop.http.HttpServer:=0A> l= istener.getLocalPort() returned 60030=0A> webServer.getConnectors()[0].getL= ocalPort() returned 60030=0A> 2011-10-18 08:27:29,700 INFO org.apache.hadoo= p.http.HttpServer: Jetty bound=0A> to port 60030=0A> 2011-10-18 08:27:29,70= 0 INFO org.mortbay.log: jetty-6.1.26=0A> 2011-10-18 08:27:29,715 WARN org.m= ortbay.log: Can't reuse=0A> /tmp/Jetty_0_0_0_0_60030_regionserver____.h599v= l, using=0A> /tmp/Jetty_0_0_0_0_60030_regionserver____.h599vl_2500151639767= 017676=0A> 2011-10-18 08:27:30,234 INFO org.mortbay.log: Started=0A> Select= ChannelConnector@0.0.0.0:60030=0A> 2011-10-18 08:27:30,236 INFO org.apache.= hadoop.ipc.HBaseServer: IPC Server=0A> Responder: starting=0A> 2011-10-18 0= 8:27:30,236 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server=0A> listener= on 57754: starting=0A> 2011-10-18 08:27:30,236 INFO org.apache.hadoop.ipc.= HBaseServer: IPC Server=0A> handler 0 on 57754: starting=0A> 2011-10-18 08:= 27:30,236 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server=0A> handler 1 = on 57754: starting=0A> 2011-10-18 08:27:30,236 INFO org.apache.hadoop.ipc.H= BaseServer: IPC Server=0A> handler 2 on 57754: starting=0A> 2011-10-18 08:2= 7:30,258 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server=0A> handler 4 o= n 57754: starting=0A> 2011-10-18 08:27:30,258 INFO org.apache.hadoop.ipc.HB= aseServer: IPC Server=0A> handler 5 on 57754: starting=0A> 2011-10-18 08:27= :30,261 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server=0A> handler 3 on= 57754: starting=0A> 2011-10-18 08:27:30,283 INFO org.apache.hadoop.ipc.HBa= seServer: IPC Server=0A> handler 7 on 57754: starting=0A> 2011-10-18 08:27:= 30,284 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server=0A> handler 8 on = 57754: starting=0A> 2011-10-18 08:27:30,284 INFO org.apache.hadoop.ipc.HBas= eServer: IPC Server=0A> handler 6 on 57754: starting=0A> 2011-10-18 08:27:3= 0,284 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server=0A> handler 9 on 5= 7754: starting=0A> 2011-10-18 08:27:30,284 INFO org.apache.hadoop.ipc.HBase= Server: PRI IPC=0A> Server handler 0 on 57754: starting=0A> 2011-10-18 08:2= 7:30,298 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC=0A> Server handler= 1 on 57754: starting=0A> 2011-10-18 08:27:30,298 INFO org.apache.hadoop.ip= c.HBaseServer: PRI IPC=0A> Server handler 2 on 57754: starting=0A> 2011-10-= 18 08:27:30,299 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC=0A> Server = handler 3 on 57754: starting=0A> 2011-10-18 08:27:30,322 INFO org.apache.ha= doop.ipc.HBaseServer: PRI IPC=0A> Server handler 4 on 57754: starting=0A> 2= 011-10-18 08:27:30,369 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC=0A> = Server handler 5 on 57754: starting=0A> 2011-10-18 08:27:30,370 INFO org.ap= ache.hadoop.ipc.HBaseServer: PRI IPC=0A> Server handler 6 on 57754: startin= g=0A> 2011-10-18 08:27:30,371 INFO org.apache.hadoop.ipc.HBaseServer: PRI I= PC=0A> Server handler 7 on 57754: starting=0A> 2011-10-18 08:27:30,371 INFO= org.apache.hadoop.ipc.HBaseServer: PRI IPC=0A> Server handler 8 on 57754: = starting=0A> 2011-10-18 08:27:30,371 INFO=0A> org.apache.hadoop.hbase.regio= nserver.HRegionServer: Serving as=0A> localhost.localdomain,57754,131894084= 4922, RPC listening on /=0A> 127.0.0.1:57754, sessionid=3D0x1331700150e0002= =0A> 2011-10-18 08:27:30,372 INFO org.apache.hadoop.ipc.HBaseServer: PRI IP= C=0A> Server handler 9 on 57754: starting=0A> 2011-10-18 08:27:30,524 INFO= =0A> org.apache.hadoop.hbase.regionserver.StoreFile: Allocating LruBlockCac= he=0A> with maximum size 199.6m=0A> 2011-10-18 08:27:30,883 INFO org.apache= .hadoop.hbase.master.ServerManager:=0A> Waiting on regionserver(s) count to= settle; currently=3D1=0A> 2011-10-18 08:27:32,385 INFO org.apache.hadoop.h= base.master.ServerManager:=0A> Finished waiting for regionserver count to s= ettle; count=3D1, sleptFor=3D4500=0A> 2011-10-18 08:27:32,385 INFO org.apac= he.hadoop.hbase.master.ServerManager:=0A> Exiting wait on regionserver(s) t= o checkin; count=3D1, stopped=3Dfalse, count of=0A> regions out on cluster= =3D0=0A> 2011-10-18 08:27:32,388 INFO=0A> org.apache.hadoop.hbase.master.Ma= sterFileSystem: Log folder=0A> file:/tmp/hbase-hbase/hbase/.logs/localhost.= localdomain,57754,1318940844922=0A> belongs to an existing region server=0A= > 2011-10-18 08:27:32,388 INFO=0A> org.apache.hadoop.hbase.master.MasterFil= eSystem: Log folder=0A> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdo= main,50781,1318937790362=0A> doesn't belong to a known region server, split= ting=0A> 2011-10-18 08:27:32,428 INFO=0A> org.apache.hadoop.hbase.regionser= ver.wal.HLogSplitter: Splitting 1 hlog(s)=0A> in=0A> file:/tmp/hbase-hbase/= hbase/.logs/localhost.localdomain,50781,1318937790362=0A> 2011-10-18 08:27:= 32,429 DEBUG=0A> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Wri= ter thread=0A> Thread[WriterThread-0,5,main]: starting=0A> 2011-10-18 08:27= :32,434 DEBUG=0A> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Sp= litting hlog 1 of=0A> 1:=0A> file:/tmp/hbase-hbase/hbase/.logs/localhost.lo= caldomain,50781,1318937790362/localhost.localdomain%3A50781.1318937795015,= =0A> length=3D307=0A> 2011-10-18 08:27:32,435 WARN org.apache.hadoop.hbase.= util.FSUtils: Running=0A> on HDFS without append enabled may result in data= loss=0A> 2011-10-18 08:27:32,440 DEBUG=0A> org.apache.hadoop.hbase.regions= erver.wal.HLogSplitter: Writer thread=0A> Thread[WriterThread-2,5,main]: st= arting=0A> 2011-10-18 08:27:32,443 DEBUG=0A> org.apache.hadoop.hbase.region= server.wal.HLogSplitter: Writer thread=0A> Thread[WriterThread-1,5,main]: s= tarting=0A> 2011-10-18 08:27:32,454 WARN=0A> org.apache.hadoop.hbase.region= server.wal.SequenceFileLogReader: Error while=0A> trying to get accurate fi= le length.=A0 Truncation / data loss may occur if=0A> RegionServers die.=0A= > java.lang.NoSuchMethodException:=0A> org.apache.hadoop.fs.ChecksumFileSys= tem$ChecksumFSInputChecker.getFileLength()=0A> at java.lang.Class.getMethod= (Class.java:1605)=0A> at=0A> org.apache.hadoop.hbase.regionserver.wal.Seque= nceFileLogReader$WALReader$WALReaderFSDataInputStream.getPos(SequenceFileLo= gReader.java:107)=0A> at org.apache.hadoop.io.SequenceFile$Reader.(Se= quenceFile.java:1436)=0A> at org.apache.hadoop.io.SequenceFile$Reader.(SequenceFile.java:1424)=0A> at org.apache.hadoop.io.SequenceFile$Reader.<= init>(SequenceFile.java:1419)=0A> at=0A> org.apache.hadoop.hbase.regionserv= er.wal.SequenceFileLogReader$WALReader.(SequenceFileLogReader.java:57= )=0A> at=0A> org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader= .init(SequenceFileLogReader.java:158)=0A> at org.apache.hadoop.hbase.region= server.wal.HLog.getReader(HLog.java:575)=0A> at=0A> org.apache.hadoop.hbase= .regionserver.wal.HLogSplitter.getReader(HLogSplitter.java:477)=0A> at=0A> = org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.parseHLog(HLogSplitte= r.java:414)=0A> at=0A> org.apache.hadoop.hbase.regionserver.wal.HLogSplitte= r.splitLog(HLogSplitter.java:262)=0A> at=0A> org.apache.hadoop.hbase.region= server.wal.HLogSplitter.splitLog(HLogSplitter.java:188)=0A> at=0A> org.apac= he.hadoop.hbase.master.MasterFileSystem.splitLog(MasterFileSystem.java:197)= =0A> at=0A> org.apache.hadoop.hbase.master.MasterFileSystem.splitLogAfterSt= artup(MasterFileSystem.java:181)=0A> at=0A> org.apache.hadoop.hbase.master.= HMaster.finishInitialization(HMaster.java:385)=0A> at org.apache.hadoop.hba= se.master.HMaster.run(HMaster.java:283)=0A> at=0A> org.apache.hadoop.hbase.= master.HMasterCommandLine$LocalHMaster.run(HMasterCommandLine.java:193)=0A>= at java.lang.Thread.run(Thread.java:619)=0A> 2011-10-18 08:27:33,063 DEBUG= =0A> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Pushed=3D1 entr= ies from=0A> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,50781,= 1318937790362/localhost.localdomain%3A50781.1318937795015=0A> 2011-10-18 08= :27:33,069 INFO=0A> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: = Archived processed=0A> log=0A> file:/tmp/hbase-hbase/hbase/.logs/localhost.= localdomain,50781,1318937790362/localhost.localdomain%3A50781.1318937795015= =0A> to=0A> file:/tmp/hbase-hbase/hbase/.oldlogs/localhost.localdomain%3A50= 781.1318937795015=0A> 2011-10-18 08:27:33,069 INFO=0A> org.apache.hadoop.hb= ase.regionserver.wal.HLogSplitter: Waiting for split=0A> writer threads to = finish=0A> 2011-10-18 08:27:33,078 INFO=0A> org.apache.hadoop.hbase.regions= erver.wal.SequenceFileLogWriter: syncFs --=0A> HDFS-200 -- not available, d= fs.support.append=3Dfalse=0A> 2011-10-18 08:27:33,079 DEBUG=0A> org.apache.= hadoop.hbase.regionserver.wal.HLogSplitter: Creating writer=0A> path=3Dfile= :/tmp/hbase-hbase/hbase/-ROOT-/70236052/recovered.edits/0000000000000000007= =0A> region=3D70236052=0A> 2011-10-18 08:27:33,079 INFO=0A> org.apache.hado= op.hbase.regionserver.wal.HLogSplitter: Split writers=0A> finished=0A> 2011= -10-18 08:27:33,080 INFO=0A> org.apache.hadoop.hbase.regionserver.wal.HLogS= plitter: Closed path=0A> file:/tmp/hbase-hbase/hbase/-ROOT-/70236052/recove= red.edits/0000000000000000007=0A> (wrote 1 edits in 17ms)=0A> 2011-10-18 08= :27:33,080 INFO=0A> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: = hlog file splitting=0A> completed in 657 ms for=0A> file:/tmp/hbase-hbase/h= base/.logs/localhost.localdomain,50781,1318937790362=0A> 2011-10-18 08:27:3= 4,087 INFO=0A> org.apache.hadoop.hbase.catalog.RootLocationEditor: Unsettin= g ROOT region=0A> location in ZooKeeper=0A> 2011-10-18 08:27:34,089 INFO=0A= > org.apache.zookeeper.server.PrepRequestProcessor: Got user-level=0A> Keep= erException when processing sessionid:0x1331700150e0000 type:delete=0A> cxi= d:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error=0A> Path:/= hbase/root-region-server Error:KeeperErrorCode =3D NoNode for=0A> /hbase/ro= ot-region-server=0A> 2011-10-18 08:27:34,092 DEBUG org.apache.hadoop.hbase.= zookeeper.ZKAssign:=0A> master:49977-0x1331700150e0000 Creating (or updatin= g) unassigned node for=0A> 70236052 with OFFLINE state=0A> 2011-10-18 08:27= :34,098 DEBUG=0A> org.apache.hadoop.hbase.master.AssignmentManager: No prev= ious transition=0A> plan was found (or we are ignoring an existing plan) fo= r -ROOT-,,0.70236052=0A> so generated a random one; hri=3D-ROOT-,,0.7023605= 2, src=3D,=0A> dest=3Dlocalhost.localdomain,57754,1318940844922; 1 (online= =3D1, exclude=3Dnull)=0A> available servers=0A> 2011-10-18 08:27:34,098 DEB= UG=0A> org.apache.hadoop.hbase.master.AssignmentManager: Assigning region= =0A> -ROOT-,,0.70236052 to localhost.localdomain,57754,1318940844922=0A> 20= 11-10-18 08:27:34,098 DEBUG org.apache.hadoop.hbase.master.ServerManager:= =0A> New connection to localhost.localdomain,57754,1318940844922=0A> 2011-1= 0-18 08:27:34,131 INFO=0A> org.apache.hadoop.hbase.regionserver.HRegionServ= er: Received request to open=0A> region: -ROOT-,,0.70236052=0A> 2011-10-18 = 08:27:34,145 DEBUG=0A> org.apache.hadoop.hbase.regionserver.handler.OpenReg= ionHandler: Processing=0A> open of -ROOT-,,0.70236052=0A> 2011-10-18 08:27:= 34,146 DEBUG org.apache.hadoop.hbase.zookeeper.ZKAssign:=0A> regionserver:5= 7754-0x1331700150e0002 Attempting to transition node=0A> 70236052/-ROOT- fr= om M_ZK_REGION_OFFLINE to RS_ZK_REGION_OPENING=0A> 2011-10-18 08:27:34,153 = DEBUG=0A> org.apache.hadoop.hbase.master.AssignmentManager: Handling=0A> tr= ansition=3DRS_ZK_REGION_OPENING,=0A> server=3Dlocalhost.localdomain,57754,1= 318940844922, region=3D70236052/-ROOT-=0A> 2011-10-18 08:27:34,154 DEBUG or= g.apache.hadoop.hbase.zookeeper.ZKAssign:=0A> regionserver:57754-0x13317001= 50e0002 Successfully transitioned node 70236052=0A> from M_ZK_REGION_OFFLIN= E to RS_ZK_REGION_OPENING=0A> 2011-10-18 08:27:34,155 DEBUG org.apache.hado= op.hbase.regionserver.HRegion:=0A> Opening region: REGION =3D> {NAME =3D> '= -ROOT-,,0', STARTKEY =3D> '', ENDKEY =3D>=0A> '', ENCODED =3D> 70236052, TA= BLE =3D> {{NAME =3D> '-ROOT-', IS_ROOT =3D> 'true',=0A> IS_META =3D> 'true'= , FAMILIES =3D> [{NAME =3D> 'info', BLOOMFILTER =3D> 'NONE',=0A> REPLICATIO= N_SCOPE =3D> '0', VERSIONS =3D> '10', COMPRESSION =3D> 'NONE', TTL =3D>=0A>= '2147483647', BLOCKSIZE =3D> '8192', IN_MEMORY =3D> 'true', BLOCKCACHE =3D= >=0A> 'true'}]}}=0A> 2011-10-18 08:27:34,156 DEBUG org.apache.hadoop.hbase.= regionserver.HRegion:=0A> Instantiated -ROOT-,,0.70236052=0A> 2011-10-18 08= :27:34,269 DEBUG org.apache.hadoop.hbase.regionserver.Store:=0A> loaded fil= e:/tmp/hbase-hbase/hbase/-ROOT-/70236052/info/2226863419614596666,=0A> isRe= ference=3Dfalse, isBulkLoadResult=3Dfalse, seqid=3D2, majorCompaction=3Dfal= se=0A> 2011-10-18 08:27:34,285 DEBUG org.apache.hadoop.hbase.regionserver.S= tore:=0A> loaded file:/tmp/hbase-hbase/hbase/-ROOT-/70236052/info/823305472= 4424930581,=0A> isReference=3Dfalse, isBulkLoadResult=3Dfalse, seqid=3D5, m= ajorCompaction=3Dfalse=0A> 2011-10-18 08:27:34,325 INFO org.apache.hadoop.h= base.regionserver.HRegion:=0A> Replaying edits from=0A> file:/tmp/hbase-hba= se/hbase/-ROOT-/70236052/recovered.edits/0000000000000000007;=0A> minSequen= ceid=3D5=0A> 2011-10-18 08:27:34,327 WARN=0A> org.apache.hadoop.hbase.regio= nserver.wal.SequenceFileLogReader: Error while=0A> trying to get accurate f= ile length.=A0 Truncation / data loss may occur if=0A> RegionServers die.= =0A> java.lang.NoSuchMethodException:=0A> org.apache.hadoop.fs.ChecksumFile= System$ChecksumFSInputChecker.getFileLength()=0A> at java.lang.Class.getMet= hod(Class.java:1605)=0A> at=0A> org.apache.hadoop.hbase.regionserver.wal.Se= quenceFileLogReader$WALReader$WALReaderFSDataInputStream.getPos(SequenceFil= eLogReader.java:107)=0A> at org.apache.hadoop.io.SequenceFile$Reader.= (SequenceFile.java:1436)=0A> at org.apache.hadoop.io.SequenceFile$Reader.(SequenceFile.java:1424)=0A> at org.apache.hadoop.io.SequenceFile$Reade= r.(SequenceFile.java:1419)=0A> at=0A> org.apache.hadoop.hbase.regions= erver.wal.SequenceFileLogReader$WALReader.(SequenceFileLogReader.java= :57)=0A> at=0A> org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogRea= der.init(SequenceFileLogReader.java:158)=0A> at org.apache.hadoop.hbase.reg= ionserver.wal.HLog.getReader(HLog.java:575)=0A> at=0A> org.apache.hadoop.hb= ase.regionserver.HRegion.replayRecoveredEdits(HRegion.java:1875)=0A> at=0A>= org.apache.hadoop.hbase.regionserver.HRegion.replayRecoveredEditsIfAny(HRe= gion.java:1835)=0A> at=0A> org.apache.hadoop.hbase.regionserver.HRegion.ini= tialize(HRegion.java:354)=0A> at=0A> org.apache.hadoop.hbase.regionserver.H= Region.openHRegion(HRegion.java:2553)=0A> at=0A> org.apache.hadoop.hbase.re= gionserver.HRegion.openHRegion(HRegion.java:2539)=0A> at=0A> org.apache.had= oop.hbase.regionserver.handler.OpenRegionHandler.openRegion(OpenRegionHandl= er.java:272)=0A> at=0A> org.apache.hadoop.hbase.regionserver.handler.OpenRe= gionHandler.process(OpenRegionHandler.java:99)=0A> at org.apache.hadoop.hba= se.executor.EventHandler.run(EventHandler.java:156)=0A> at=0A> java.util.co= ncurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)=0A>= at=0A> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecut= or.java:908)=0A> at java.lang.Thread.run(Thread.java:619)=0A> 2011-10-18 08= :27:34,329 DEBUG org.apache.hadoop.hbase.regionserver.HRegion:=0A> Applied = 2, skipped 0, firstSequenceidInLog=3D7, maxSequenceidInLog=3D7=0A> 2011-10-= 18 08:27:34,329 DEBUG org.apache.hadoop.hbase.regionserver.HRegion:=0A> Sta= rted memstore flush for -ROOT-,,0.70236052, current region memstore size=0A= > 480.0; wal is null, using passed sequenceid=3D7=0A> 2011-10-18 08:27:34,3= 29 DEBUG org.apache.hadoop.hbase.regionserver.HRegion:=0A> Finished snapsho= tting, commencing flushing stores=0A> 2011-10-18 08:27:34,417 INFO org.apac= he.hadoop.hbase.regionserver.Store:=0A> Renaming flushed file at=0A> file:/= tmp/hbase-hbase/hbase/-ROOT-/70236052/.tmp/7507925448071592382 to=0A> file:= /tmp/hbase-hbase/hbase/-ROOT-/70236052/info/6152135076013786807=0A> 2011-10= -18 08:27:34,419 INFO org.apache.hadoop.hbase.regionserver.Store:=0A> Added= file:/tmp/hbase-hbase/hbase/-ROOT-/70236052/info/6152135076013786807,=0A> = entries=3D2, sequenceid=3D7, memsize=3D480.0, filesize=3D514.0=0A> 2011-10-= 18 08:27:34,419 INFO org.apache.hadoop.hbase.regionserver.HRegion:=0A> Fini= shed memstore flush of ~480.0 for region -ROOT-,,0.70236052 in 90ms,=0A> se= quenceid=3D7, compaction requested=3Dtrue; wal=3Dnull=0A> 2011-10-18 08:27:= 34,443 DEBUG org.apache.hadoop.hbase.regionserver.HRegion:=0A> Deleted reco= vered.edits=0A> file=3Dfile:/tmp/hbase-hbase/hbase/-ROOT-/70236052/recovere= d.edits/0000000000000000007=0A> 2011-10-18 08:27:34,444 INFO org.apache.had= oop.hbase.regionserver.HRegion:=0A> Onlined -ROOT-,,0.70236052; next sequen= ceid=3D8=0A> 2011-10-18 08:27:34,444 DEBUG org.apache.hadoop.hbase.zookeepe= r.ZKAssign:=0A> regionserver:57754-0x1331700150e0002 Attempting to transiti= on node=0A> 70236052/-ROOT- from RS_ZK_REGION_OPENING to RS_ZK_REGION_OPENI= NG=0A> 2011-10-18 08:27:34,449 DEBUG=0A> org.apache.hadoop.hbase.master.Ass= ignmentManager: Handling=0A> transition=3DRS_ZK_REGION_OPENING,=0A> server= =3Dlocalhost.localdomain,57754,1318940844922, region=3D70236052/-ROOT-=0A> = 2011-10-18 08:27:34,449 DEBUG org.apache.hadoop.hbase.zookeeper.ZKAssign:= =0A> regionserver:57754-0x1331700150e0002 Successfully transitioned node 70= 236052=0A> from RS_ZK_REGION_OPENING to RS_ZK_REGION_OPENING=0A> 2011-10-18= 08:27:34,450 INFO=0A> org.apache.hadoop.hbase.catalog.RootLocationEditor: = Setting ROOT region=0A> location in ZooKeeper as localhost.localdomain:5775= 4=0A> 2011-10-18 08:27:34,456 INFO org.apache.hadoop.hbase.master.HMaster: = -ROOT-=0A> assigned=3D1, rit=3Dfalse, location=3Dlocalhost.localdomain:5775= 4=0A> 2011-10-18 08:27:34,473 INFO org.apache.hadoop.ipc.HbaseRPC: Server a= t=0A> localhost.localdomain/127.0.0.1:50781 could not be reached after 1 tr= ies,=0A> giving up.=0A> 2011-10-18 08:27:34,473 INFO=0A> org.apache.hadoop.= hbase.catalog.CatalogTracker: Passed metaserver is null=0A> 2011-10-18 08:2= 7:34,473 DEBUG org.apache.hadoop.hbase.zookeeper.ZKAssign:=0A> master:49977= -0x1331700150e0000 Creating (or updating) unassigned node for=0A> 102878519= 2 with OFFLINE state=0A> 2011-10-18 08:27:34,477 DEBUG=0A> org.apache.hadoo= p.hbase.master.AssignmentManager: Handling=0A> transition=3DM_ZK_REGION_OFF= LINE, server=3Dlocalhost.localdomain:49977,=0A> region=3D1028785192/.META.= =0A> 2011-10-18 08:27:34,478 DEBUG=0A> org.apache.hadoop.hbase.master.Assig= nmentManager: No previous transition=0A> plan was found (or we are ignoring= an existing plan) for=0A> .META.,,1.1028785192 so generated a random one; = hri=3D.META.,,1.1028785192,=0A> src=3D, dest=3Dlocalhost.localdomain,57754,= 1318940844922; 1 (online=3D1,=0A> exclude=3Dnull) available servers=0A> 201= 1-10-18 08:27:34,478 DEBUG=0A> org.apache.hadoop.hbase.master.AssignmentMan= ager: Assigning region=0A> .META.,,1.1028785192 to localhost.localdomain,57= 754,1318940844922=0A> 2011-10-18 08:27:34,479 INFO=0A> org.apache.hadoop.hb= ase.regionserver.HRegionServer: Received request to open=0A> region: .META.= ,,1.1028785192=0A> 2011-10-18 08:27:34,645 DEBUG=0A> org.apache.hadoop.hbas= e.regionserver.handler.OpenRegionHandler: Processing=0A> open of .META.,,1.= 1028785192=0A> 2011-10-18 08:27:34,645 DEBUG org.apache.hadoop.hbase.zookee= per.ZKAssign:=0A> regionserver:57754-0x1331700150e0002 Attempting to transi= tion node=0A> 1028785192/.META. from M_ZK_REGION_OFFLINE to RS_ZK_REGION_OP= ENING=0A> 2011-10-18 08:27:34,647 DEBUG org.apache.hadoop.hbase.zookeeper.Z= KAssign:=0A> regionserver:57754-0x1331700150e0002 Attempting to transition = node=0A> 70236052/-ROOT- from RS_ZK_REGION_OPENING to RS_ZK_REGION_OPENED= =0A> 2011-10-18 08:27:34,655 DEBUG=0A> org.apache.hadoop.hbase.master.Assig= nmentManager: Handling=0A> transition=3DRS_ZK_REGION_OPENING,=0A> server=3D= localhost.localdomain,57754,1318940844922, region=3D1028785192/.META.=0A> 2= 011-10-18 08:27:34,656 DEBUG org.apache.hadoop.hbase.zookeeper.ZKAssign:=0A= > regionserver:57754-0x1331700150e0002 Successfully transitioned node=0A> 1= 028785192 from M_ZK_REGION_OFFLINE to RS_ZK_REGION_OPENING=0A> 2011-10-18 0= 8:27:34,656 DEBUG org.apache.hadoop.hbase.regionserver.HRegion:=0A> Opening= region: REGION =3D> {NAME =3D> '.META.,,1', STARTKEY =3D> '', ENDKEY =3D>= =0A> '', ENCODED =3D> 1028785192, TABLE =3D> {{NAME =3D> '.META.', IS_META = =3D> 'true',=0A> FAMILIES =3D> [{NAME =3D> 'info', BLOOMFILTER =3D> 'NONE',= REPLICATION_SCOPE =3D>=0A> '0', VERSIONS =3D> '10', COMPRESSION =3D> 'NONE= ', TTL =3D> '2147483647', BLOCKSIZE=0A> =3D> '8192', IN_MEMORY =3D> 'true',= BLOCKCACHE =3D> 'true'}]}}=0A> 2011-10-18 08:27:34,657 DEBUG org.apache.ha= doop.hbase.regionserver.HRegion:=0A> Instantiated .META.,,1.1028785192=0A> = 2011-10-18 08:27:34,696 INFO org.apache.hadoop.hbase.regionserver.HRegion:= =0A> Onlined .META.,,1.1028785192; next sequenceid=3D1=0A> 2011-10-18 08:27= :34,697 DEBUG org.apache.hadoop.hbase.zookeeper.ZKAssign:=0A> regionserver:= 57754-0x1331700150e0002 Attempting to transition node=0A> 1028785192/.META.= from RS_ZK_REGION_OPENING to RS_ZK_REGION_OPENING=0A> 2011-10-18 08:27:34,= 726 DEBUG=0A> org.apache.hadoop.hbase.master.AssignmentManager: Handling=0A= > transition=3DRS_ZK_REGION_OPENED,=0A> server=3Dlocalhost.localdomain,5775= 4,1318940844922, region=3D70236052/-ROOT-=0A> 2011-10-18 08:27:34,727 DEBUG= =0A> org.apache.hadoop.hbase.master.handler.OpenedRegionHandler: Handling O= PENED=0A> event for 70236052; deleting unassigned node=0A> 2011-10-18 08:27= :34,727 DEBUG org.apache.hadoop.hbase.zookeeper.ZKAssign:=0A> master:49977-= 0x1331700150e0000 Deleting existing unassigned node for=0A> 70236052 that i= s in expected state RS_ZK_REGION_OPENED=0A> 2011-10-18 08:27:34,729 DEBUG o= rg.apache.hadoop.hbase.zookeeper.ZKAssign:=0A> regionserver:57754-0x1331700= 150e0002 Successfully transitioned node 70236052=0A> from RS_ZK_REGION_OPEN= ING to RS_ZK_REGION_OPENED=0A> 2011-10-18 08:27:34,729 DEBUG=0A> org.apache= .hadoop.hbase.regionserver.handler.OpenRegionHandler: Opened=0A> -ROOT-,,0.= 70236052=0A> 2011-10-18 08:27:34,735 DEBUG org.apache.hadoop.hbase.zookeepe= r.ZKAssign:=0A> master:49977-0x1331700150e0000 Successfully deleted unassig= ned node for=0A> region 70236052 in expected state RS_ZK_REGION_OPENED=0A> = 2011-10-18 08:27:34,735 DEBUG=0A> org.apache.hadoop.hbase.master.handler.Op= enedRegionHandler: Opened region=0A> -ROOT-,,0.70236052 on localhost.locald= omain,57754,1318940844922=0A> 2011-10-18 08:27:34,737 DEBUG=0A> org.apache.= hadoop.hbase.master.AssignmentManager: Handling=0A> transition=3DRS_ZK_REGI= ON_OPENING,=0A> server=3Dlocalhost.localdomain,57754,1318940844922, region= =3D1028785192/.META.=0A> 2011-10-18 08:27:34,738 DEBUG org.apache.hadoop.hb= ase.zookeeper.ZKAssign:=0A> regionserver:57754-0x1331700150e0002 Successful= ly transitioned node=0A> 1028785192 from RS_ZK_REGION_OPENING to RS_ZK_REGI= ON_OPENING=0A> 2011-10-18 08:27:34,742 INFO org.apache.hadoop.hbase.catalog= .MetaEditor:=0A> Updated row .META.,,1.1028785192 in region -ROOT-,,0 with= =0A> server=3Dlocalhost.localdomain:57754, startcode=3D1318940844922=0A> 20= 11-10-18 08:27:34,742 DEBUG org.apache.hadoop.hbase.zookeeper.ZKAssign:=0A>= regionserver:57754-0x1331700150e0002 Attempting to transition node=0A> 102= 8785192/.META. from RS_ZK_REGION_OPENING to RS_ZK_REGION_OPENED=0A> 2011-10= -18 08:27:34,745 DEBUG=0A> org.apache.hadoop.hbase.master.AssignmentManager= : Handling=0A> transition=3DRS_ZK_REGION_OPENED,=0A> server=3Dlocalhost.loc= aldomain,57754,1318940844922, region=3D1028785192/.META.=0A> 2011-10-18 08:= 27:34,746 DEBUG=0A> org.apache.hadoop.hbase.master.handler.OpenedRegionHand= ler: Handling OPENED=0A> event for 1028785192; deleting unassigned node=0A>= 2011-10-18 08:27:34,746 DEBUG org.apache.hadoop.hbase.zookeeper.ZKAssign:= =0A> master:49977-0x1331700150e0000 Deleting existing unassigned node for= =0A> 1028785192 that is in expected state RS_ZK_REGION_OPENED=0A> 2011-10-1= 8 08:27:34,748 DEBUG org.apache.hadoop.hbase.zookeeper.ZKAssign:=0A> region= server:57754-0x1331700150e0002 Successfully transitioned node=0A> 102878519= 2 from RS_ZK_REGION_OPENING to RS_ZK_REGION_OPENED=0A> 2011-10-18 08:27:34,= 748 DEBUG=0A> org.apache.hadoop.hbase.regionserver.handler.OpenRegionHandle= r: Opened=0A> .META.,,1.1028785192=0A> 2011-10-18 08:27:34,749 INFO=0A> org= .apache.hadoop.hbase.zookeeper.MetaNodeTracker: Detected completed=0A> assi= gnment of META, notifying catalog tracker=0A> 2011-10-18 08:27:34,750 INFO= =0A> org.apache.hadoop.hbase.zookeeper.MetaNodeTracker: Detected completed= =0A> assignment of META, notifying catalog tracker=0A> 2011-10-18 08:27:34,= 751 DEBUG org.apache.hadoop.hbase.zookeeper.ZKAssign:=0A> master:49977-0x13= 31700150e0000 Successfully deleted unassigned node for=0A> region 102878519= 2 in expected state RS_ZK_REGION_OPENED=0A> 2011-10-18 08:27:34,751 DEBUG= =0A> org.apache.hadoop.hbase.master.handler.OpenedRegionHandler: Opened reg= ion=0A> .META.,,1.1028785192 on localhost.localdomain,57754,1318940844922= =0A> 2011-10-18 08:27:34,755 INFO org.apache.hadoop.hbase.master.HMaster: .= META.=0A> assigned=3D2, rit=3Dfalse, location=3Dlocalhost.localdomain:57754= =0A> 2011-10-18 08:27:34,755 INFO org.apache.hadoop.hbase.master.HMaster: M= aster=0A> startup proceeding: cluster startup=0A> 2011-10-18 08:27:34,755 D= EBUG org.apache.hadoop.hbase.zookeeper.ZKAssign:=0A> master:49977-0x1331700= 150e0000 Deleting any existing unassigned nodes=0A> 2011-10-18 08:27:35,035= INFO org.apache.hadoop.hbase.master.HMaster: Master=0A> has completed init= ialization=0A> 2011-10-18 08:27:35,038 DEBUG=0A> org.apache.hadoop.hbase.ma= ster.CatalogJanitor: Scanned 0 catalog row(s) and=0A> gc'd 0 unreferenced p= arent region(s)=0A> 2011-10-18 08:28:06,767 INFO org.apache.zookeeper.ZooKe= eper: Initiating=0A> client connection, connectString=3Dlocalhost:2182 sess= ionTimeout=3D180000=0A> watcher=3Dhconnection=0A> 2011-10-18 08:28:06,768 I= NFO org.apache.zookeeper.ClientCnxn: Opening=0A> socket connection to serve= r localhost/127.0.0.1:2182=0A> 2011-10-18 08:28:06,768 INFO org.apache.zook= eeper.server.NIOServerCnxn:=0A> Accepted socket connection from /127.0.0.1:= 48880=0A> 2011-10-18 08:28:06,769 INFO org.apache.zookeeper.ClientCnxn: Soc= ket=0A> connection established to localhost/127.0.0.1:2182, initiating sess= ion=0A> 2011-10-18 08:28:06,769 INFO org.apache.zookeeper.server.NIOServerC= nxn:=0A> Client attempting to establish new session at /127.0.0.1:48880=0A>= 2011-10-18 08:28:06,780 INFO org.apache.zookeeper.server.NIOServerCnxn:=0A= > Established session 0x1331700150e0004 with negotiated timeout 40000 for= =0A> client /127.0.0.1:48880=0A> 2011-10-18 08:28:06,782 INFO org.apache.zo= okeeper.ClientCnxn: Session=0A> establishment complete on server localhost/= 127.0.0.1:2182, sessionid =3D=0A> 0x1331700150e0004, negotiated timeout =3D= 40000=0A> 2011-10-18 08:28:06,800 DEBUG org.apache.hadoop.hbase.client.Met= aScanner:=0A> Scanning .META. starting at row=3D for max=3D2147483647 rows= =0A> 2011-10-18 08:28:06,801 DEBUG=0A> org.apache.hadoop.hbase.client.HConn= ectionManager$HConnectionImplementation:=0A> Lookedup root region location,= =0A> connection=3Dorg.apache.hadoop.hbase.client.HConnectionManager$HConnec= tionImplementation@1b4b74a7;=0A> hsa=3Dlocalhost.localdomain:57754=0A> 2011= -10-18 08:28:06,822 DEBUG=0A> org.apache.hadoop.hbase.client.HConnectionMan= ager$HConnectionImplementation:=0A> Cached location for .META.,,1.102878519= 2 is localhost.localdomain:57754=0A> 2011-10-18 08:28:06,824 INFO=0A> org.a= pache.hadoop.hbase.client.HConnectionManager$HConnectionImplementation:=0A>= Closed zookeeper sessionid=3D0x1331700150e0004=0A> 2011-10-18 08:28:06,824= INFO=0A> org.apache.zookeeper.server.PrepRequestProcessor: Processed sessi= on=0A> termination for sessionid: 0x1331700150e0004=0A> 2011-10-18 08:28:06= ,828 INFO org.apache.zookeeper.server.NIOServerCnxn:=0A> Closed socket conn= ection for client /127.0.0.1:48880 which had sessionid=0A> 0x1331700150e000= 4=0A> 2011-10-18 08:28:06,829 INFO org.apache.zookeeper.ZooKeeper: Session:= =0A> 0x1331700150e0004 closed=0A> 2011-10-18 08:28:06,837 INFO org.apache.z= ookeeper.ClientCnxn: EventThread=0A> shut down=0A>=0A