problem starting standalone hbase on Ubuntu 11.04

[Blake Lemoine <ba...@gmail.com>]

Hello,

   I'm having problems getting HBase up and running on my Ubuntu 11.04
installation.  I'm not really certain what's wrong but many of the posts of
similar issues I'm finding reference changes to /etc/hosts .  Unfortunately
the lines that those suggestions say to delete are not in my hosts file.
 Below is a copy of the log as well as a copy of my /etc/hosts file.

Wed Jun 29 08:26:07 CDT 2011 Starting master on wittgenstein
ulimit -n 1024
2011-06-29 08:26:07,713 INFO org.apache.zookeeper.server.ZooKeeperServer:
Server environment:zookeeper.version=3.3.3-cdh3u0--1, built on 03/26/2011
00:20 GMT
2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
Server environment:host.name=wittgenstein
2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
Server environment:java.version=1.6.0_24
2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
Server environment:java.vendor=Sun Microsystems Inc.
2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
Server environment:java.home=/usr/lib/jvm/java-6-sun-1.6.0.24/jre
2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
Server
environment:java.class.path=/usr/lib/hbase/conf:/usr/lib/jvm/java-6-sun/lib/tools.jar:/usr/lib/hbase:/usr/lib/hbase/hbase-0.90.1-cdh3u0.jar:/usr/lib/hbase/hbase-0.90.1-cdh3u0-tests.jar:/usr/lib/hbase/lib/activation-1.1.jar:/usr/lib/hbase/lib/asm-3.1.jar:/usr/lib/hbase/lib/avro-1.3.3.jar:/usr/lib/hbase/lib/commons-cli-1.2.jar:/usr/lib/hbase/lib/commons-codec-1.4.jar:/usr/lib/hbase/lib/commons-el-1.0.jar:/usr/lib/hbase/lib/commons-httpclient-3.1.jar:/usr/lib/hbase/lib/commons-lang-2.5.jar:/usr/lib/hbase/lib/commons-logging-1.1.1.jar:/usr/lib/hbase/lib/commons-net-1.4.1.jar:/usr/lib/hbase/lib/core-3.1.1.jar:/usr/lib/hbase/lib/guava-r06.jar:/usr/lib/hbase/lib/hadoop-core.jar:/usr/lib/hbase/lib/hbase-0.90.1-cdh3u0.jar:/usr/lib/hbase/lib/jackson-core-asl-1.5.2.jar:/usr/lib/hbase/lib/jackson-jaxrs-1.5.5.jar:/usr/lib/hbase/lib/jackson-mapper-asl-1.5.2.jar:/usr/lib/hbase/lib/jackson-xc-1.5.5.jar:/usr/lib/hbase/lib/jasper-compiler-5.5.23.jar:/usr/lib/hbase/lib/jasper-runtime-5.5.23.jar:/usr/lib/hbase/lib/jaxb-api-2.1.jar:/usr/lib/hbase/lib/jaxb-impl-2.1.12.jar:/usr/lib/hbase/lib/jersey-core-1.4.jar:/usr/lib/hbase/lib/jersey-json-1.4.jar:/usr/lib/hbase/lib/jersey-server-1.4.jar:/usr/lib/hbase/lib/jettison-1.1.jar:/usr/lib/hbase/lib/jetty-6.1.26.jar:/usr/lib/hbase/lib/jetty-util-6.1.26.jar:/usr/lib/hbase/lib/jruby-complete-1.0.3.jar:/usr/lib/hbase/lib/jsp-2.1-6.1.14.jar:/usr/lib/hbase/lib/jsp-api-2.1-6.1.14.jar:/usr/lib/hbase/lib/jsp-api-2.1.jar:/usr/lib/hbase/lib/jsr311-api-1.1.1.jar:/usr/lib/hbase/lib/log4j-1.2.16.jar:/usr/lib/hbase/lib/protobuf-java-2.3.0.jar:/usr/lib/hbase/lib/servlet-api-2.5-6.1.14.jar:/usr/lib/hbase/lib/servlet-api-2.5.jar:/usr/lib/hbase/lib/slf4j-api-1.5.8.jar:/usr/lib/hbase/lib/slf4j-log4j12-1.5.8.jar:/usr/lib/hbase/lib/stax-api-1.0.1.jar:/usr/lib/hbase/lib/thrift-0.2.0.jar:/usr/lib/hbase/lib/xmlenc-0.52.jar:/usr/lib/hbase/lib/zookeeper.jar
2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
Server
environment:java.library.path=/usr/lib/jvm/java-6-sun-1.6.0.24/jre/lib/amd64/server:/usr/lib/jvm/java-6-sun-1.6.0.24/jre/lib/amd64:/usr/lib/jvm/java-6-sun-1.6.0.24/jre/../lib/amd64:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
Server environment:java.io.tmpdir=/tmp
2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
Server environment:java.compiler=<NA>
2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
Server environment:os.name=Linux
2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
Server environment:os.arch=amd64
2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
Server environment:os.version=2.6.38-8-generic
2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
Server environment:user.name=hbase
2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
Server environment:user.home=/var/lib/hbase
2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
Server environment:user.dir=/var/log/hbase
2011-06-29 08:26:07,725 INFO org.apache.zookeeper.server.ZooKeeperServer:
Created server with tickTime 2000 minSessionTimeout 4000 maxSessionTimeout
40000 datadir /tmp/hbase-hbase/zookeeper/zookeeper/version-2 snapdir
/tmp/hbase-hbase/zookeeper/zookeeper/version-2
2011-06-29 08:26:07,741 INFO org.apache.zookeeper.server.NIOServerCnxn:
binding to port 0.0.0.0/0.0.0.0:2181
2011-06-29 08:26:07,745 INFO
org.apache.zookeeper.server.persistence.FileTxnSnapLog: Snapshotting: 0
2011-06-29 08:26:07,793 INFO org.apache.zookeeper.server.NIOServerCnxn:
Accepted socket connection from /127.0.0.1:48200
2011-06-29 08:26:07,796 INFO org.apache.zookeeper.server.NIOServerCnxn:
Processing stat command from /127.0.0.1:48200
2011-06-29 08:26:07,802 INFO org.apache.zookeeper.server.NIOServerCnxn: Stat
command output
2011-06-29 08:26:07,802 INFO org.apache.zookeeper.server.NIOServerCnxn:
Closed socket connection for client /127.0.0.1:48200 (no session established
for client)
2011-06-29 08:26:07,803 INFO
org.apache.hadoop.hbase.zookeeper.MiniZooKeeperCluster: Started MiniZK
Server on client port: 2181
2011-06-29 08:26:07,841 INFO org.apache.hadoop.hbase.ipc.HBaseRpcMetrics:
Initializing RPC Metrics with hostName=HMasterCommandLine$LocalHMaster,
port=52888
2011-06-29 08:26:07,968 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
Responder: starting
2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
listener on 52888: starting
2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 0 on 52888: starting
2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 1 on 52888: starting
2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 2 on 52888: starting
2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 3 on 52888: starting
2011-06-29 08:26:07,970 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 6 on 52888: starting
2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 5 on 52888: starting
2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 4 on 52888: starting
2011-06-29 08:26:07,970 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 7 on 52888: starting
2011-06-29 08:26:07,970 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 8 on 52888: starting
2011-06-29 08:26:07,977 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 9 on 52888: starting
2011-06-29 08:26:07,979 INFO org.apache.zookeeper.ZooKeeper: Client
environment:zookeeper.version=3.3.3-cdh3u0--1, built on 03/26/2011 00:20 GMT
2011-06-29 08:26:07,979 INFO org.apache.zookeeper.ZooKeeper: Client
environment:host.name=wittgenstein
2011-06-29 08:26:07,979 INFO org.apache.zookeeper.ZooKeeper: Client
environment:java.version=1.6.0_24
2011-06-29 08:26:07,979 INFO org.apache.zookeeper.ZooKeeper: Client
environment:java.vendor=Sun Microsystems Inc.
2011-06-29 08:26:07,979 INFO org.apache.zookeeper.ZooKeeper: Client
environment:java.home=/usr/lib/jvm/java-6-sun-1.6.0.24/jre
2011-06-29 08:26:07,979 INFO org.apache.zookeeper.ZooKeeper: Client
environment:java.class.path=/usr/lib/hbase/conf:/usr/lib/jvm/java-6-sun/lib/tools.jar:/usr/lib/hbase:/usr/lib/hbase/hbase-0.90.1-cdh3u0.jar:/usr/lib/hbase/hbase-0.90.1-cdh3u0-tests.jar:/usr/lib/hbase/lib/activation-1.1.jar:/usr/lib/hbase/lib/asm-3.1.jar:/usr/lib/hbase/lib/avro-1.3.3.jar:/usr/lib/hbase/lib/commons-cli-1.2.jar:/usr/lib/hbase/lib/commons-codec-1.4.jar:/usr/lib/hbase/lib/commons-el-1.0.jar:/usr/lib/hbase/lib/commons-httpclient-3.1.jar:/usr/lib/hbase/lib/commons-lang-2.5.jar:/usr/lib/hbase/lib/commons-logging-1.1.1.jar:/usr/lib/hbase/lib/commons-net-1.4.1.jar:/usr/lib/hbase/lib/core-3.1.1.jar:/usr/lib/hbase/lib/guava-r06.jar:/usr/lib/hbase/lib/hadoop-core.jar:/usr/lib/hbase/lib/hbase-0.90.1-cdh3u0.jar:/usr/lib/hbase/lib/jackson-core-asl-1.5.2.jar:/usr/lib/hbase/lib/jackson-jaxrs-1.5.5.jar:/usr/lib/hbase/lib/jackson-mapper-asl-1.5.2.jar:/usr/lib/hbase/lib/jackson-xc-1.5.5.jar:/usr/lib/hbase/lib/jasper-compiler-5.5.23.jar:/usr/lib/hbase/lib/jasper-runtime-5.5.23.jar:/usr/lib/hbase/lib/jaxb-api-2.1.jar:/usr/lib/hbase/lib/jaxb-impl-2.1.12.jar:/usr/lib/hbase/lib/jersey-core-1.4.jar:/usr/lib/hbase/lib/jersey-json-1.4.jar:/usr/lib/hbase/lib/jersey-server-1.4.jar:/usr/lib/hbase/lib/jettison-1.1.jar:/usr/lib/hbase/lib/jetty-6.1.26.jar:/usr/lib/hbase/lib/jetty-util-6.1.26.jar:/usr/lib/hbase/lib/jruby-complete-1.0.3.jar:/usr/lib/hbase/lib/jsp-2.1-6.1.14.jar:/usr/lib/hbase/lib/jsp-api-2.1-6.1.14.jar:/usr/lib/hbase/lib/jsp-api-2.1.jar:/usr/lib/hbase/lib/jsr311-api-1.1.1.jar:/usr/lib/hbase/lib/log4j-1.2.16.jar:/usr/lib/hbase/lib/protobuf-java-2.3.0.jar:/usr/lib/hbase/lib/servlet-api-2.5-6.1.14.jar:/usr/lib/hbase/lib/servlet-api-2.5.jar:/usr/lib/hbase/lib/slf4j-api-1.5.8.jar:/usr/lib/hbase/lib/slf4j-log4j12-1.5.8.jar:/usr/lib/hbase/lib/stax-api-1.0.1.jar:/usr/lib/hbase/lib/thrift-0.2.0.jar:/usr/lib/hbase/lib/xmlenc-0.52.jar:/usr/lib/hbase/lib/zookeeper.jar
2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
environment:java.library.path=/usr/lib/jvm/java-6-sun-1.6.0.24/jre/lib/amd64/server:/usr/lib/jvm/java-6-sun-1.6.0.24/jre/lib/amd64:/usr/lib/jvm/java-6-sun-1.6.0.24/jre/../lib/amd64:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
environment:java.io.tmpdir=/tmp
2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
environment:java.compiler=<NA>
2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
environment:os.name=Linux
2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
environment:os.arch=amd64
2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
environment:os.version=2.6.38-8-generic
2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
environment:user.name=hbase
2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
environment:user.home=/var/lib/hbase
2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
environment:user.dir=/var/log/hbase
2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Initiating
client connection, connectString=localhost:2181 sessionTimeout=180000
watcher=master:52888
2011-06-29 08:26:07,988 INFO org.apache.zookeeper.ClientCnxn: Opening socket
connection to server localhost/127.0.0.1:2181
2011-06-29 08:26:07,988 INFO org.apache.zookeeper.server.NIOServerCnxn:
Accepted socket connection from /127.0.0.1:48201
2011-06-29 08:26:07,989 INFO org.apache.zookeeper.ClientCnxn: Socket
connection established to localhost/127.0.0.1:2181, initiating session
2011-06-29 08:26:07,992 INFO org.apache.zookeeper.server.NIOServerCnxn:
Client attempting to establish new session at /127.0.0.1:48201
2011-06-29 08:26:07,995 INFO
org.apache.zookeeper.server.persistence.FileTxnLog: Creating new log file:
log.1
2011-06-29 08:26:08,038 INFO org.apache.zookeeper.server.NIOServerCnxn:
Established session 0x130db9400860000 with negotiated timeout 40000 for
client /127.0.0.1:48201
2011-06-29 08:26:08,038 INFO org.apache.zookeeper.ClientCnxn: Session
establishment complete on server localhost/127.0.0.1:2181, sessionid =
0x130db9400860000, negotiated timeout = 40000
2011-06-29 08:26:08,089 INFO org.apache.hadoop.metrics.jvm.JvmMetrics:
Initializing JVM Metrics with processName=Master,
sessionId=wittgenstein:52888
2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
added: revision
2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
added: hdfsUser
2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
added: hdfsDate
2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
added: hdfsUrl
2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
added: date
2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
added: hdfsRevision
2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
added: user
2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
added: hdfsVersion
2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
added: url
2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
added: version
2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: new MBeanInfo
2011-06-29 08:26:08,100 INFO org.apache.hadoop.hbase.metrics: new MBeanInfo
2011-06-29 08:26:08,100 INFO
org.apache.hadoop.hbase.master.metrics.MasterMetrics: Initialized
2011-06-29 08:26:08,142 INFO org.apache.zookeeper.ZooKeeper: Initiating
client connection, connectString=localhost:2181 sessionTimeout=180000
watcher=hconnection
2011-06-29 08:26:08,142 INFO org.apache.zookeeper.ClientCnxn: Opening socket
connection to server localhost/0:0:0:0:0:0:0:1:2181
2011-06-29 08:26:08,142 INFO org.apache.zookeeper.ClientCnxn: Socket
connection established to localhost/0:0:0:0:0:0:0:1:2181, initiating session
2011-06-29 08:26:08,143 INFO org.apache.zookeeper.server.NIOServerCnxn:
Accepted socket connection from /0:0:0:0:0:0:0:1:48008
2011-06-29 08:26:08,143 INFO org.apache.zookeeper.server.NIOServerCnxn:
Client attempting to establish new session at /0:0:0:0:0:0:0:1:48008
2011-06-29 08:26:08,152 INFO org.apache.zookeeper.server.NIOServerCnxn:
Established session 0x130db9400860001 with negotiated timeout 40000 for
client /0:0:0:0:0:0:0:1:48008
2011-06-29 08:26:08,152 INFO org.apache.zookeeper.ClientCnxn: Session
establishment complete on server localhost/0:0:0:0:0:0:0:1:2181, sessionid =
0x130db9400860001, negotiated timeout = 40000
2011-06-29 08:26:08,154 INFO
org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
KeeperException when processing sessionid:0x130db9400860001 type:create
cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/hbase Error:KeeperErrorCode = NodeExists for /hbase
2011-06-29 08:26:08,161 INFO
org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
KeeperException when processing sessionid:0x130db9400860001 type:create
cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/hbase/unassigned Error:KeeperErrorCode = NodeExists for
/hbase/unassigned
2011-06-29 08:26:08,169 INFO
org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
KeeperException when processing sessionid:0x130db9400860001 type:create
cxid:0x3 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/hbase/rs Error:KeeperErrorCode = NodeExists for /hbase/rs
2011-06-29 08:26:08,178 INFO
org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
KeeperException when processing sessionid:0x130db9400860001 type:create
cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/hbase/table Error:KeeperErrorCode = NodeExists for /hbase/table
2011-06-29 08:26:08,198 INFO org.apache.hadoop.hbase.ipc.HBaseRpcMetrics:
Initializing RPC Metrics with hostName=HRegionServer, port=49976
2011-06-29 08:26:08,216 INFO org.apache.zookeeper.ZooKeeper: Initiating
client connection, connectString=localhost:2181 sessionTimeout=180000
watcher=regionserver:49976
2011-06-29 08:26:08,217 INFO org.apache.zookeeper.ClientCnxn: Opening socket
connection to server localhost/127.0.0.1:2181
2011-06-29 08:26:08,217 INFO org.apache.zookeeper.ClientCnxn: Socket
connection established to localhost/127.0.0.1:2181, initiating session
2011-06-29 08:26:08,217 INFO org.apache.zookeeper.server.NIOServerCnxn:
Accepted socket connection from /127.0.0.1:48203
2011-06-29 08:26:08,218 INFO org.apache.zookeeper.server.NIOServerCnxn:
Client attempting to establish new session at /127.0.0.1:48203
2011-06-29 08:26:08,228 INFO
org.apache.hadoop.hbase.master.ActiveMasterManager:
Master=wittgenstein:52888
2011-06-29 08:26:08,235 INFO org.apache.zookeeper.server.NIOServerCnxn:
Established session 0x130db9400860002 with negotiated timeout 40000 for
client /127.0.0.1:48203
2011-06-29 08:26:08,236 INFO
org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
KeeperException when processing sessionid:0x130db9400860002 type:create
cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/hbase Error:KeeperErrorCode = NodeExists for /hbase
2011-06-29 08:26:08,246 INFO org.apache.zookeeper.ClientCnxn: Session
establishment complete on server localhost/127.0.0.1:2181, sessionid =
0x130db9400860002, negotiated timeout = 40000
2011-06-29 08:26:08,246 INFO
org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
KeeperException when processing sessionid:0x130db9400860002 type:create
cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/hbase/unassigned Error:KeeperErrorCode = NodeExists for
/hbase/unassigned
2011-06-29 08:26:08,252 INFO
org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
KeeperException when processing sessionid:0x130db9400860002 type:create
cxid:0x3 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/hbase/rs Error:KeeperErrorCode = NodeExists for /hbase/rs
2011-06-29 08:26:08,260 INFO
org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
KeeperException when processing sessionid:0x130db9400860002 type:create
cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/hbase/table Error:KeeperErrorCode = NodeExists for /hbase/table
2011-06-29 08:26:08,294 INFO org.apache.zookeeper.ZooKeeper: Initiating
client connection, connectString=localhost:2181 sessionTimeout=180000
watcher=hconnection
2011-06-29 08:26:08,294 INFO org.apache.zookeeper.ClientCnxn: Opening socket
connection to server localhost/0:0:0:0:0:0:0:1:2181
2011-06-29 08:26:08,294 INFO org.apache.zookeeper.ClientCnxn: Socket
connection established to localhost/0:0:0:0:0:0:0:1:2181, initiating session
2011-06-29 08:26:08,294 INFO org.apache.zookeeper.server.NIOServerCnxn:
Accepted socket connection from /0:0:0:0:0:0:0:1:48010
2011-06-29 08:26:08,294 INFO org.apache.zookeeper.server.NIOServerCnxn:
Client attempting to establish new session at /0:0:0:0:0:0:0:1:48010
2011-06-29 08:26:08,302 INFO org.apache.zookeeper.server.NIOServerCnxn:
Established session 0x130db9400860003 with negotiated timeout 40000 for
client /0:0:0:0:0:0:0:1:48010
2011-06-29 08:26:08,302 INFO org.apache.zookeeper.ClientCnxn: Session
establishment complete on server localhost/0:0:0:0:0:0:0:1:2181, sessionid =
0x130db9400860003, negotiated timeout = 40000
2011-06-29 08:26:08,302 INFO
org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
KeeperException when processing sessionid:0x130db9400860003 type:create
cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/hbase Error:KeeperErrorCode = NodeExists for /hbase
2011-06-29 08:26:08,310 INFO
org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
KeeperException when processing sessionid:0x130db9400860003 type:create
cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/hbase/unassigned Error:KeeperErrorCode = NodeExists for
/hbase/unassigned
2011-06-29 08:26:08,319 INFO
org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
KeeperException when processing sessionid:0x130db9400860003 type:create
cxid:0x3 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/hbase/rs Error:KeeperErrorCode = NodeExists for /hbase/rs
2011-06-29 08:26:08,327 INFO
org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
KeeperException when processing sessionid:0x130db9400860003 type:create
cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/hbase/table Error:KeeperErrorCode = NodeExists for /hbase/table
2011-06-29 08:26:08,353 DEBUG
org.apache.hadoop.hbase.catalog.CatalogTracker: Starting catalog tracker
org.apache.hadoop.hbase.catalog.CatalogTracker@3fe2670b
2011-06-29 08:26:08,379 INFO org.apache.hadoop.hbase.master.HMaster: Server
active/primary master; wittgenstein:52888, sessionid=0x130db9400860000,
cluster-up flag was=false
2011-06-29 08:26:08,380 DEBUG
org.apache.hadoop.hbase.catalog.CatalogTracker: Starting catalog tracker
org.apache.hadoop.hbase.catalog.CatalogTracker@a68cb6b
2011-06-29 08:26:08,383 DEBUG
org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
name=MASTER_OPEN_REGION-wittgenstein:52888, corePoolSize=5, maxPoolSize=5
2011-06-29 08:26:08,383 DEBUG
org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
name=MASTER_CLOSE_REGION-wittgenstein:52888, corePoolSize=5, maxPoolSize=5
2011-06-29 08:26:08,383 DEBUG
org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
name=MASTER_SERVER_OPERATIONS-wittgenstein:52888, corePoolSize=3,
maxPoolSize=3
2011-06-29 08:26:08,383 DEBUG
org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
name=MASTER_META_SERVER_OPERATIONS-wittgenstein:52888, corePoolSize=5,
maxPoolSize=5
2011-06-29 08:26:08,384 DEBUG
org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
name=MASTER_TABLE_OPERATIONS-wittgenstein:52888, corePoolSize=1,
maxPoolSize=1
2011-06-29 08:26:08,386 DEBUG org.apache.hadoop.hbase.master.LogCleaner: Add
log cleaner in chain: org.apache.hadoop.hbase.master.TimeToLiveLogCleaner
2011-06-29 08:26:08,389 INFO
org.apache.hadoop.hbase.regionserver.MemStoreFlusher:
globalMemStoreLimit=396.7m, globalMemStoreLimitLowMark=347.1m,
maxHeap=991.7m
2011-06-29 08:26:08,395 DEBUG
org.apache.hadoop.hbase.regionserver.PriorityCompactionQueue: Create
PriorityCompactionQueue
2011-06-29 08:26:08,396 INFO
org.apache.hadoop.hbase.regionserver.HRegionServer: Runs every 10000000ms
2011-06-29 08:26:08,428 INFO
org.apache.hadoop.hbase.regionserver.HRegionServer: Attempting connect to
Master server at wittgenstein:52888
2011-06-29 08:26:08,503 INFO
org.apache.hadoop.hbase.regionserver.HRegionServer: Master isn't available
yet, retrying
2011-06-29 08:26:08,517 INFO org.mortbay.log: Logging to
org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via
org.mortbay.log.Slf4jLog
2011-06-29 08:26:08,566 INFO org.apache.hadoop.http.HttpServer: Added global
filtersafety (class=org.apache.hadoop.http.HttpServer$QuotingInputFilter)
2011-06-29 08:26:08,569 INFO org.apache.hadoop.http.HttpServer: Port
returned by webServer.getConnectors()[0].getLocalPort() before open() is -1.
Opening the listener on 60010
2011-06-29 08:26:08,569 INFO org.apache.hadoop.http.HttpServer:
listener.getLocalPort() returned 60010
webServer.getConnectors()[0].getLocalPort() returned 60010
2011-06-29 08:26:08,569 INFO org.apache.hadoop.http.HttpServer: Jetty bound
to port 60010
2011-06-29 08:26:08,569 INFO org.mortbay.log: jetty-6.1.26
2011-06-29 08:26:08,864 INFO org.mortbay.log: Started
SelectChannelConnector@0.0.0.0:60010
2011-06-29 08:26:08,864 DEBUG org.apache.hadoop.hbase.master.HMaster:
Started service threads
2011-06-29 08:26:10,364 INFO org.apache.hadoop.hbase.master.ServerManager:
Waiting on regionserver(s) to checkin
2011-06-29 08:26:11,503 INFO
org.apache.hadoop.hbase.regionserver.HRegionServer: Attempting connect to
Master server at wittgenstein:52888
2011-06-29 08:26:11,505 INFO
org.apache.hadoop.hbase.regionserver.HRegionServer: Connected to master at
wittgenstein:52888
2011-06-29 08:26:11,550 INFO
org.apache.hadoop.hbase.regionserver.HRegionServer: Telling master at
wittgenstein:52888 that we are up
2011-06-29 08:26:11,553 INFO org.apache.hadoop.hbase.master.ServerManager:
Registering server=localhost.localdomain,49976,1309353968214, regionCount=0,
userLoad=false
2011-06-29 08:26:11,564 INFO
org.apache.hadoop.hbase.regionserver.HRegionServer: Master passed us address
to use. Was=wittgenstein:49976, Now=localhost.localdomain:49976
2011-06-29 08:26:11,564 DEBUG
org.apache.hadoop.hbase.regionserver.HRegionServer: Config from master:
fs.default.name=file:///
2011-06-29 08:26:11,564 DEBUG
org.apache.hadoop.hbase.regionserver.HRegionServer: Config from master:
hbase.rootdir=file:///tmp/hbase-hbase/hbase
2011-06-29 08:26:11,569 DEBUG
org.apache.hadoop.hbase.regionserver.HRegionServer:
logdir=file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,49976,1309353968214
2011-06-29 08:26:11,572 INFO org.apache.hadoop.hbase.regionserver.wal.HLog:
HLog configuration: blocksize=32 MB, rollsize=30.4 MB, enabled=true,
flushlogentries=1, optionallogflushinternal=1000ms
2011-06-29 08:26:11,603 WARN org.apache.hadoop.util.NativeCodeLoader: Unable
to load native-hadoop library for your platform... using builtin-java
classes where applicable
2011-06-29 08:26:11,610 INFO
org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogWriter: syncFs --
HDFS-200 -- not available, dfs.support.append=false
2011-06-29 08:26:11,611 INFO org.apache.hadoop.hbase.regionserver.wal.HLog:
New hlog
/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,49976,1309353968214/localhost.localdomain%3A49976.1309353971573
2011-06-29 08:26:11,611 INFO org.apache.hadoop.hbase.regionserver.wal.HLog:
getNumCurrentReplicas--HDFS-826 not available;
hdfs_out=org.apache.hadoop.fs.ChecksumFileSystem$ChecksumFSOutputSummer@79ca209e,
exception=org.apache.hadoop.fs.ChecksumFileSystem$ChecksumFSOutputSummer.getNumCurrentReplicas()
2011-06-29 08:26:11,612 INFO org.apache.hadoop.metrics.jvm.JvmMetrics:
Cannot initialize JVM Metrics with processName=RegionServer,
sessionId=RegionServer:0;wittgenstein,49976,1309353968214 - already
initialized
2011-06-29 08:26:11,613 INFO org.apache.hadoop.hbase.metrics: new MBeanInfo
2011-06-29 08:26:11,613 INFO
org.apache.hadoop.hbase.regionserver.metrics.RegionServerMetrics:
Initialized
2011-06-29 08:26:11,614 DEBUG
org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
name=RS_OPEN_REGION-localhost.localdomain,49976,1309353968214,
corePoolSize=3, maxPoolSize=3
2011-06-29 08:26:11,614 DEBUG
org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
name=RS_OPEN_ROOT-localhost.localdomain,49976,1309353968214, corePoolSize=1,
maxPoolSize=1
2011-06-29 08:26:11,614 DEBUG
org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
name=RS_OPEN_META-localhost.localdomain,49976,1309353968214, corePoolSize=1,
maxPoolSize=1
2011-06-29 08:26:11,614 DEBUG
org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
name=RS_CLOSE_REGION-localhost.localdomain,49976,1309353968214,
corePoolSize=3, maxPoolSize=3
2011-06-29 08:26:11,614 DEBUG
org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
name=RS_CLOSE_ROOT-localhost.localdomain,49976,1309353968214,
corePoolSize=1, maxPoolSize=1
2011-06-29 08:26:11,614 DEBUG
org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
name=RS_CLOSE_META-localhost.localdomain,49976,1309353968214,
corePoolSize=1, maxPoolSize=1
2011-06-29 08:26:11,630 INFO org.apache.hadoop.http.HttpServer: Added global
filtersafety (class=org.apache.hadoop.http.HttpServer$QuotingInputFilter)
2011-06-29 08:26:11,630 INFO org.apache.hadoop.http.HttpServer: Port
returned by webServer.getConnectors()[0].getLocalPort() before open() is -1.
Opening the listener on 60030
2011-06-29 08:26:11,630 INFO org.apache.hadoop.http.HttpServer:
listener.getLocalPort() returned 60030
webServer.getConnectors()[0].getLocalPort() returned 60030
2011-06-29 08:26:11,630 INFO org.apache.hadoop.http.HttpServer: Jetty bound
to port 60030
2011-06-29 08:26:11,630 INFO org.mortbay.log: jetty-6.1.26
2011-06-29 08:26:11,750 INFO org.mortbay.log: Started
SelectChannelConnector@0.0.0.0:60030
2011-06-29 08:26:11,757 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
Responder: starting
2011-06-29 08:26:11,758 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
listener on 49976: starting
2011-06-29 08:26:11,758 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 0 on 49976: starting
2011-06-29 08:26:11,758 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 1 on 49976: starting
2011-06-29 08:26:11,759 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 2 on 49976: starting
2011-06-29 08:26:11,759 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 3 on 49976: starting
2011-06-29 08:26:11,759 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 4 on 49976: starting
2011-06-29 08:26:11,759 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 5 on 49976: starting
2011-06-29 08:26:11,759 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 6 on 49976: starting
2011-06-29 08:26:11,759 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 7 on 49976: starting
2011-06-29 08:26:11,760 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 8 on 49976: starting
2011-06-29 08:26:11,760 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
handler 9 on 49976: starting
2011-06-29 08:26:11,760 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
Server handler 0 on 49976: starting
2011-06-29 08:26:11,760 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
Server handler 1 on 49976: starting
2011-06-29 08:26:11,760 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
Server handler 2 on 49976: starting
2011-06-29 08:26:11,761 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
Server handler 3 on 49976: starting
2011-06-29 08:26:11,761 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
Server handler 4 on 49976: starting
2011-06-29 08:26:11,761 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
Server handler 5 on 49976: starting
2011-06-29 08:26:11,761 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
Server handler 6 on 49976: starting
2011-06-29 08:26:11,761 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
Server handler 7 on 49976: starting
2011-06-29 08:26:11,762 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
Server handler 8 on 49976: starting
2011-06-29 08:26:11,762 INFO
org.apache.hadoop.hbase.regionserver.HRegionServer: Serving as
localhost.localdomain,49976,1309353968214, RPC listening on /127.0.1.1:49976,
sessionid=0x130db9400860002
2011-06-29 08:26:11,763 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
Server handler 9 on 49976: starting
2011-06-29 08:26:11,766 INFO org.apache.hadoop.hbase.regionserver.StoreFile:
Allocating LruBlockCache with maximum size 198.3m
2011-06-29 08:26:11,865 INFO org.apache.hadoop.hbase.master.ServerManager:
Waiting on regionserver(s) count to settle; currently=1
2011-06-29 08:26:13,365 INFO org.apache.hadoop.hbase.master.ServerManager:
Finished waiting for regionserver count to settle; count=1, sleptFor=4500
2011-06-29 08:26:13,366 INFO org.apache.hadoop.hbase.master.ServerManager:
Exiting wait on regionserver(s) to checkin; count=1, stopped=false, count of
regions out on cluster=0
2011-06-29 08:26:13,368 INFO
org.apache.hadoop.hbase.master.MasterFileSystem: Log folder
file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,49976,1309353968214
belongs to an existing region server
2011-06-29 08:26:13,368 INFO
org.apache.hadoop.hbase.master.MasterFileSystem: Log folder
file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764
doesn't belong to a known region server, splitting
2011-06-29 08:26:13,381 INFO
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Splitting 1 hlog(s)
in
file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764
2011-06-29 08:26:13,382 DEBUG
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Writer thread
Thread[WriterThread-0,5,main]: starting
2011-06-29 08:26:13,387 DEBUG
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Writer thread
Thread[WriterThread-1,5,main]: starting
2011-06-29 08:26:13,388 DEBUG
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Writer thread
Thread[WriterThread-2,5,main]: starting
2011-06-29 08:26:13,388 DEBUG
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Splitting hlog 1 of
1:
file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764/localhost.localdomain%3A38896.1309353525054,
length=0
2011-06-29 08:26:13,389 WARN org.apache.hadoop.hbase.util.FSUtils: Running
on HDFS without append enabled may result in data loss
2011-06-29 08:26:13,389 WARN
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: File
file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764/localhost.localdomain%3A38896.1309353525054
might be still open, length is 0
2011-06-29 08:26:13,399 WARN org.apache.hadoop.fs.FSInputChecker: Problem
opening checksum file:
file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764/localhost.localdomain%3A38896.1309353525054.
 Ignoring exception: java.io.EOFException
at java.io.DataInputStream.readFully(DataInputStream.java:180)
at java.io.DataInputStream.readFully(DataInputStream.java:152)
at
org.apache.hadoop.fs.ChecksumFileSystem$ChecksumFSInputChecker.<init>(ChecksumFileSystem.java:135)
at org.apache.hadoop.fs.ChecksumFileSystem.open(ChecksumFileSystem.java:284)
at org.apache.hadoop.io.SequenceFile$Reader.openFile(SequenceFile.java:1452)
at
org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader$WALReader.openFile(SequenceFileLogReader.java:65)
at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1431)
at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1424)
at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1419)
at
org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader$WALReader.<init>(SequenceFileLogReader.java:57)
at
org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader.init(SequenceFileLogReader.java:158)
at org.apache.hadoop.hbase.regionserver.wal.HLog.getReader(HLog.java:576)
at
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.getReader(HLogSplitter.java:477)
at
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.parseHLog(HLogSplitter.java:414)
at
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.splitLog(HLogSplitter.java:262)
at
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.splitLog(HLogSplitter.java:188)
at
org.apache.hadoop.hbase.master.MasterFileSystem.splitLog(MasterFileSystem.java:197)
at
org.apache.hadoop.hbase.master.MasterFileSystem.splitLogAfterStartup(MasterFileSystem.java:181)
at
org.apache.hadoop.hbase.master.HMaster.finishInitialization(HMaster.java:384)
at org.apache.hadoop.hbase.master.HMaster.run(HMaster.java:283)
at
org.apache.hadoop.hbase.master.HMasterCommandLine$LocalHMaster.run(HMasterCommandLine.java:193)
at java.lang.Thread.run(Thread.java:662)

2011-06-29 08:26:13,402 WARN
org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader: Error while
trying to get accurate file length.  Truncation / data loss may occur if
RegionServers die.
java.lang.NoSuchMethodException:
org.apache.hadoop.fs.ChecksumFileSystem$ChecksumFSInputChecker.getFileLength()
at java.lang.Class.getMethod(Class.java:1605)
at
org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader$WALReader$WALReaderFSDataInputStream.getPos(SequenceFileLogReader.java:107)
at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1436)
at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1424)
at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1419)
at
org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader$WALReader.<init>(SequenceFileLogReader.java:57)
at
org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader.init(SequenceFileLogReader.java:158)
at org.apache.hadoop.hbase.regionserver.wal.HLog.getReader(HLog.java:576)
at
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.getReader(HLogSplitter.java:477)
at
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.parseHLog(HLogSplitter.java:414)
at
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.splitLog(HLogSplitter.java:262)
at
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.splitLog(HLogSplitter.java:188)
at
org.apache.hadoop.hbase.master.MasterFileSystem.splitLog(MasterFileSystem.java:197)
at
org.apache.hadoop.hbase.master.MasterFileSystem.splitLogAfterStartup(MasterFileSystem.java:181)
at
org.apache.hadoop.hbase.master.HMaster.finishInitialization(HMaster.java:384)
at org.apache.hadoop.hbase.master.HMaster.run(HMaster.java:283)
at
org.apache.hadoop.hbase.master.HMasterCommandLine$LocalHMaster.run(HMasterCommandLine.java:193)
at java.lang.Thread.run(Thread.java:662)
2011-06-29 08:26:13,405 WARN
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Could not open
file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764/localhost.localdomain%3A38896.1309353525054
for reading. File is emptyjava.io.EOFException
2011-06-29 08:26:13,408 INFO
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Archived processed
log
file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764/localhost.localdomain%3A38896.1309353525054
to
file:/tmp/hbase-hbase/hbase/.oldlogs/localhost.localdomain%3A38896.1309353525054
2011-06-29 08:26:13,409 INFO
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Waiting for split
writer threads to finish
2011-06-29 08:26:14,388 INFO
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Split writers
finished
2011-06-29 08:26:14,389 INFO
org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: hlog file splitting
completed in 1010 ms for
file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764
2011-06-29 08:26:15,394 INFO
org.apache.hadoop.hbase.catalog.RootLocationEditor: Unsetting ROOT region
location in ZooKeeper
2011-06-29 08:26:15,396 INFO
org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
KeeperException when processing sessionid:0x130db9400860000 type:delete
cxid:0x11 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/hbase/root-region-server Error:KeeperErrorCode = NoNode for
/hbase/root-region-server
2011-06-29 08:26:15,423 DEBUG org.apache.hadoop.hbase.zookeeper.ZKAssign:
master:52888-0x130db9400860000 Creating (or updating) unassigned node for
70236052 with OFFLINE state
2011-06-29 08:26:15,440 DEBUG
org.apache.hadoop.hbase.master.AssignmentManager: No previous transition
plan was found (or we are ignoring an existing plan) for -ROOT-,,0.70236052
so generated a random one; hri=-ROOT-,,0.70236052, src=,
dest=localhost.localdomain,49976,1309353968214; 1 (online=1, exclude=null)
available servers
2011-06-29 08:26:15,440 DEBUG
org.apache.hadoop.hbase.master.AssignmentManager: Assigning region
-ROOT-,,0.70236052 to localhost.localdomain,49976,1309353968214
2011-06-29 08:26:15,440 DEBUG org.apache.hadoop.hbase.master.ServerManager:
New connection to localhost.localdomain,49976,1309353968214
2011-06-29 08:26:15,462 INFO org.apache.hadoop.ipc.HbaseRPC: Server at /
127.0.0.1:49976 could not be reached after 1 tries, giving up.
2011-06-29 08:26:15,463 WARN
org.apache.hadoop.hbase.master.AssignmentManager: Failed assignment of
-ROOT-,,0.70236052 to serverName=localhost.localdomain,49976,1309353968214,
load=(requests=0, regions=0, usedHeap=31, maxHeap=991), trying to assign
elsewhere instead; retry=0
org.apache.hadoop.hbase.client.RetriesExhaustedException: Failed setting up
proxy interface org.apache.hadoop.hbase.ipc.HRegionInterface to /
127.0.0.1:49976 after attempts=1
at org.apache.hadoop.hbase.ipc.HBaseRPC.waitForProxy(HBaseRPC.java:355)
at
org.apache.hadoop.hbase.client.HConnectionManager$HConnectionImplementation.getHRegionConnection(HConnectionManager.java:957)
at
org.apache.hadoop.hbase.master.ServerManager.getServerConnection(ServerManager.java:606)
at
org.apache.hadoop.hbase.master.ServerManager.sendRegionOpen(ServerManager.java:541)
at
org.apache.hadoop.hbase.master.AssignmentManager.assign(AssignmentManager.java:901)
at
org.apache.hadoop.hbase.master.AssignmentManager.assign(AssignmentManager.java:730)
at
org.apache.hadoop.hbase.master.AssignmentManager.assign(AssignmentManager.java:710)
at
org.apache.hadoop.hbase.master.AssignmentManager.assignRoot(AssignmentManager.java:1170)
at
org.apache.hadoop.hbase.master.HMaster.assignRootAndMeta(HMaster.java:431)
at
org.apache.hadoop.hbase.master.HMaster.finishInitialization(HMaster.java:388)
at org.apache.hadoop.hbase.master.HMaster.run(HMaster.java:283)
at
org.apache.hadoop.hbase.master.HMasterCommandLine$LocalHMaster.run(HMasterCommandLine.java:193)
at java.lang.Thread.run(Thread.java:662)
Caused by: java.net.ConnectException: Connection refused
at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:567)
at
org.apache.hadoop.net.SocketIOWithTimeout.connect(SocketIOWithTimeout.java:206)
at org.apache.hadoop.net.NetUtils.connect(NetUtils.java:408)
at
org.apache.hadoop.hbase.ipc.HBaseClient$Connection.setupIOstreams(HBaseClient.java:328)
at
org.apache.hadoop.hbase.ipc.HBaseClient.getConnection(HBaseClient.java:883)
at org.apache.hadoop.hbase.ipc.HBaseClient.call(HBaseClient.java:750)
at org.apache.hadoop.hbase.ipc.HBaseRPC$Invoker.invoke(HBaseRPC.java:257)
at $Proxy7.getProtocolVersion(Unknown Source)
at org.apache.hadoop.hbase.ipc.HBaseRPC.getProxy(HBaseRPC.java:419)
at org.apache.hadoop.hbase.ipc.HBaseRPC.getProxy(HBaseRPC.java:393)
at org.apache.hadoop.hbase.ipc.HBaseRPC.getProxy(HBaseRPC.java:444)
at org.apache.hadoop.hbase.ipc.HBaseRPC.waitForProxy(HBaseRPC.java:349)
... 12 more
2011-06-29 08:26:15,463 WARN
org.apache.hadoop.hbase.master.AssignmentManager: Unable to find a viable
location to assign region -ROOT-,,0.70236052

127.0.0.1 localhost.localdomain localhost
::1 wittgenstein localhost6.localdomain6 localhost6
127.0.1.1 wittgenstein

# The following lines are desirable for IPv6 capable hosts
::1     localhost ip6-localhost ip6-loopback
fe00::0 ip6-localnet
ff00::0 ip6-mcastprefix
ff02::1 ip6-allnodes
ff02::2 ip6-allrouters
ff02::3 ip6-allhosts

Re: problem starting standalone hbase on Ubuntu 11.04

[Blake Lemoine <ba...@gmail.com>]

Thanks.  What ended up being the problem was that at most points of the
startup process HBase uses "localhost" as the default.  But for some reason
when it's starting the region servers in standalone mode it uses the
system's name as the default.  I modified /etc/hosts so that both localhost
and my machine's name point to 127.0.0.1 and now it works.

On Wed, Jun 29, 2011 at 9:15 AM, Stack <st...@duboce.net> wrote:

> Can you fix this:
>
> 2011-06-29 08:26:11,762 INFO
> org.apache.hadoop.hbase.regionserver.HRegionServer: Serving as
> localhost.localdomain,49976,1309353968214, RPC listening on
> /127.0.1.1:49976, sessionid=0x130db9400860002
>
> Make it so servers are 127.0.0.1 or localhost.localdomain, not both.
>
> St.Ack
>
> On Wed, Jun 29, 2011 at 6:58 AM, Blake Lemoine <ba...@gmail.com> wrote:
> > Hello,
> >
> >   I'm having problems getting HBase up and running on my Ubuntu 11.04
> > installation.  I'm not really certain what's wrong but many of the posts
> of
> > similar issues I'm finding reference changes to /etc/hosts .
>  Unfortunately
> > the lines that those suggestions say to delete are not in my hosts file.
> >  Below is a copy of the log as well as a copy of my /etc/hosts file.
> >
> > Wed Jun 29 08:26:07 CDT 2011 Starting master on wittgenstein
> > ulimit -n 1024
> > 2011-06-29 08:26:07,713 INFO org.apache.zookeeper.server.ZooKeeperServer:
> > Server environment:zookeeper.version=3.3.3-cdh3u0--1, built on 03/26/2011
> > 00:20 GMT
> > 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> > Server environment:host.name=wittgenstein
> > 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> > Server environment:java.version=1.6.0_24
> > 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> > Server environment:java.vendor=Sun Microsystems Inc.
> > 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> > Server environment:java.home=/usr/lib/jvm/java-6-sun-1.6.0.24/jre
> > 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> > Server
> >
> environment:java.class.path=/usr/lib/hbase/conf:/usr/lib/jvm/java-6-sun/lib/tools.jar:/usr/lib/hbase:/usr/lib/hbase/hbase-0.90.1-cdh3u0.jar:/usr/lib/hbase/hbase-0.90.1-cdh3u0-tests.jar:/usr/lib/hbase/lib/activation-1.1.jar:/usr/lib/hbase/lib/asm-3.1.jar:/usr/lib/hbase/lib/avro-1.3.3.jar:/usr/lib/hbase/lib/commons-cli-1.2.jar:/usr/lib/hbase/lib/commons-codec-1.4.jar:/usr/lib/hbase/lib/commons-el-1.0.jar:/usr/lib/hbase/lib/commons-httpclient-3.1.jar:/usr/lib/hbase/lib/commons-lang-2.5.jar:/usr/lib/hbase/lib/commons-logging-1.1.1.jar:/usr/lib/hbase/lib/commons-net-1.4.1.jar:/usr/lib/hbase/lib/core-3.1.1.jar:/usr/lib/hbase/lib/guava-r06.jar:/usr/lib/hbase/lib/hadoop-core.jar:/usr/lib/hbase/lib/hbase-0.90.1-cdh3u0.jar:/usr/lib/hbase/lib/jackson-core-asl-1.5.2.jar:/usr/lib/hbase/lib/jackson-jaxrs-1.5.5.jar:/usr/lib/hbase/lib/jackson-mapper-asl-1.5.2.jar:/usr/lib/hbase/lib/jackson-xc-1.5.5.jar:/usr/lib/hbase/lib/jasper-compiler-5.5.23.jar:/usr/lib/hbase/lib/jasper-runtime-5.5.23.jar:/usr/lib/hbase/lib/jaxb-api-2.1.jar:/usr/lib/hbase/lib/jaxb-impl-2.1.12.jar:/usr/lib/hbase/lib/jersey-core-1.4.jar:/usr/lib/hbase/lib/jersey-json-1.4.jar:/usr/lib/hbase/lib/jersey-server-1.4.jar:/usr/lib/hbase/lib/jettison-1.1.jar:/usr/lib/hbase/lib/jetty-6.1.26.jar:/usr/lib/hbase/lib/jetty-util-6.1.26.jar:/usr/lib/hbase/lib/jruby-complete-1.0.3.jar:/usr/lib/hbase/lib/jsp-2.1-6.1.14.jar:/usr/lib/hbase/lib/jsp-api-2.1-6.1.14.jar:/usr/lib/hbase/lib/jsp-api-2.1.jar:/usr/lib/hbase/lib/jsr311-api-1.1.1.jar:/usr/lib/hbase/lib/log4j-1.2.16.jar:/usr/lib/hbase/lib/protobuf-java-2.3.0.jar:/usr/lib/hbase/lib/servlet-api-2.5-6.1.14.jar:/usr/lib/hbase/lib/servlet-api-2.5.jar:/usr/lib/hbase/lib/slf4j-api-1.5.8.jar:/usr/lib/hbase/lib/slf4j-log4j12-1.5.8.jar:/usr/lib/hbase/lib/stax-api-1.0.1.jar:/usr/lib/hbase/lib/thrift-0.2.0.jar:/usr/lib/hbase/lib/xmlenc-0.52.jar:/usr/lib/hbase/lib/zookeeper.jar
> > 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> > Server
> >
> environment:java.library.path=/usr/lib/jvm/java-6-sun-1.6.0.24/jre/lib/amd64/server:/usr/lib/jvm/java-6-sun-1.6.0.24/jre/lib/amd64:/usr/lib/jvm/java-6-sun-1.6.0.24/jre/../lib/amd64:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
> > 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> > Server environment:java.io.tmpdir=/tmp
> > 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> > Server environment:java.compiler=<NA>
> > 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> > Server environment:os.name=Linux
> > 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> > Server environment:os.arch=amd64
> > 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> > Server environment:os.version=2.6.38-8-generic
> > 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> > Server environment:user.name=hbase
> > 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> > Server environment:user.home=/var/lib/hbase
> > 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> > Server environment:user.dir=/var/log/hbase
> > 2011-06-29 08:26:07,725 INFO org.apache.zookeeper.server.ZooKeeperServer:
> > Created server with tickTime 2000 minSessionTimeout 4000
> maxSessionTimeout
> > 40000 datadir /tmp/hbase-hbase/zookeeper/zookeeper/version-2 snapdir
> > /tmp/hbase-hbase/zookeeper/zookeeper/version-2
> > 2011-06-29 08:26:07,741 INFO org.apache.zookeeper.server.NIOServerCnxn:
> > binding to port 0.0.0.0/0.0.0.0:2181
> > 2011-06-29 08:26:07,745 INFO
> > org.apache.zookeeper.server.persistence.FileTxnSnapLog: Snapshotting: 0
> > 2011-06-29 08:26:07,793 INFO org.apache.zookeeper.server.NIOServerCnxn:
> > Accepted socket connection from /127.0.0.1:48200
> > 2011-06-29 08:26:07,796 INFO org.apache.zookeeper.server.NIOServerCnxn:
> > Processing stat command from /127.0.0.1:48200
> > 2011-06-29 08:26:07,802 INFO org.apache.zookeeper.server.NIOServerCnxn:
> Stat
> > command output
> > 2011-06-29 08:26:07,802 INFO org.apache.zookeeper.server.NIOServerCnxn:
> > Closed socket connection for client /127.0.0.1:48200 (no session
> established
> > for client)
> > 2011-06-29 08:26:07,803 INFO
> > org.apache.hadoop.hbase.zookeeper.MiniZooKeeperCluster: Started MiniZK
> > Server on client port: 2181
> > 2011-06-29 08:26:07,841 INFO org.apache.hadoop.hbase.ipc.HBaseRpcMetrics:
> > Initializing RPC Metrics with hostName=HMasterCommandLine$LocalHMaster,
> > port=52888
> > 2011-06-29 08:26:07,968 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > Responder: starting
> > 2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > listener on 52888: starting
> > 2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 0 on 52888: starting
> > 2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 1 on 52888: starting
> > 2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 2 on 52888: starting
> > 2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 3 on 52888: starting
> > 2011-06-29 08:26:07,970 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 6 on 52888: starting
> > 2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 5 on 52888: starting
> > 2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 4 on 52888: starting
> > 2011-06-29 08:26:07,970 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 7 on 52888: starting
> > 2011-06-29 08:26:07,970 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 8 on 52888: starting
> > 2011-06-29 08:26:07,977 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 9 on 52888: starting
> > 2011-06-29 08:26:07,979 INFO org.apache.zookeeper.ZooKeeper: Client
> > environment:zookeeper.version=3.3.3-cdh3u0--1, built on 03/26/2011 00:20
> GMT
> > 2011-06-29 08:26:07,979 INFO org.apache.zookeeper.ZooKeeper: Client
> > environment:host.name=wittgenstein
> > 2011-06-29 08:26:07,979 INFO org.apache.zookeeper.ZooKeeper: Client
> > environment:java.version=1.6.0_24
> > 2011-06-29 08:26:07,979 INFO org.apache.zookeeper.ZooKeeper: Client
> > environment:java.vendor=Sun Microsystems Inc.
> > 2011-06-29 08:26:07,979 INFO org.apache.zookeeper.ZooKeeper: Client
> > environment:java.home=/usr/lib/jvm/java-6-sun-1.6.0.24/jre
> > 2011-06-29 08:26:07,979 INFO org.apache.zookeeper.ZooKeeper: Client
> >
> environment:java.class.path=/usr/lib/hbase/conf:/usr/lib/jvm/java-6-sun/lib/tools.jar:/usr/lib/hbase:/usr/lib/hbase/hbase-0.90.1-cdh3u0.jar:/usr/lib/hbase/hbase-0.90.1-cdh3u0-tests.jar:/usr/lib/hbase/lib/activation-1.1.jar:/usr/lib/hbase/lib/asm-3.1.jar:/usr/lib/hbase/lib/avro-1.3.3.jar:/usr/lib/hbase/lib/commons-cli-1.2.jar:/usr/lib/hbase/lib/commons-codec-1.4.jar:/usr/lib/hbase/lib/commons-el-1.0.jar:/usr/lib/hbase/lib/commons-httpclient-3.1.jar:/usr/lib/hbase/lib/commons-lang-2.5.jar:/usr/lib/hbase/lib/commons-logging-1.1.1.jar:/usr/lib/hbase/lib/commons-net-1.4.1.jar:/usr/lib/hbase/lib/core-3.1.1.jar:/usr/lib/hbase/lib/guava-r06.jar:/usr/lib/hbase/lib/hadoop-core.jar:/usr/lib/hbase/lib/hbase-0.90.1-cdh3u0.jar:/usr/lib/hbase/lib/jackson-core-asl-1.5.2.jar:/usr/lib/hbase/lib/jackson-jaxrs-1.5.5.jar:/usr/lib/hbase/lib/jackson-mapper-asl-1.5.2.jar:/usr/lib/hbase/lib/jackson-xc-1.5.5.jar:/usr/lib/hbase/lib/jasper-compiler-5.5.23.jar:/usr/lib/hbase/lib/jasper-runtime-5.5.23.jar:/usr/lib/hbase/lib/jaxb-api-2.1.jar:/usr/lib/hbase/lib/jaxb-impl-2.1.12.jar:/usr/lib/hbase/lib/jersey-core-1.4.jar:/usr/lib/hbase/lib/jersey-json-1.4.jar:/usr/lib/hbase/lib/jersey-server-1.4.jar:/usr/lib/hbase/lib/jettison-1.1.jar:/usr/lib/hbase/lib/jetty-6.1.26.jar:/usr/lib/hbase/lib/jetty-util-6.1.26.jar:/usr/lib/hbase/lib/jruby-complete-1.0.3.jar:/usr/lib/hbase/lib/jsp-2.1-6.1.14.jar:/usr/lib/hbase/lib/jsp-api-2.1-6.1.14.jar:/usr/lib/hbase/lib/jsp-api-2.1.jar:/usr/lib/hbase/lib/jsr311-api-1.1.1.jar:/usr/lib/hbase/lib/log4j-1.2.16.jar:/usr/lib/hbase/lib/protobuf-java-2.3.0.jar:/usr/lib/hbase/lib/servlet-api-2.5-6.1.14.jar:/usr/lib/hbase/lib/servlet-api-2.5.jar:/usr/lib/hbase/lib/slf4j-api-1.5.8.jar:/usr/lib/hbase/lib/slf4j-log4j12-1.5.8.jar:/usr/lib/hbase/lib/stax-api-1.0.1.jar:/usr/lib/hbase/lib/thrift-0.2.0.jar:/usr/lib/hbase/lib/xmlenc-0.52.jar:/usr/lib/hbase/lib/zookeeper.jar
> > 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
> >
> environment:java.library.path=/usr/lib/jvm/java-6-sun-1.6.0.24/jre/lib/amd64/server:/usr/lib/jvm/java-6-sun-1.6.0.24/jre/lib/amd64:/usr/lib/jvm/java-6-sun-1.6.0.24/jre/../lib/amd64:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
> > 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
> > environment:java.io.tmpdir=/tmp
> > 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
> > environment:java.compiler=<NA>
> > 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
> > environment:os.name=Linux
> > 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
> > environment:os.arch=amd64
> > 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
> > environment:os.version=2.6.38-8-generic
> > 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
> > environment:user.name=hbase
> > 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
> > environment:user.home=/var/lib/hbase
> > 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
> > environment:user.dir=/var/log/hbase
> > 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Initiating
> > client connection, connectString=localhost:2181 sessionTimeout=180000
> > watcher=master:52888
> > 2011-06-29 08:26:07,988 INFO org.apache.zookeeper.ClientCnxn: Opening
> socket
> > connection to server localhost/127.0.0.1:2181
> > 2011-06-29 08:26:07,988 INFO org.apache.zookeeper.server.NIOServerCnxn:
> > Accepted socket connection from /127.0.0.1:48201
> > 2011-06-29 08:26:07,989 INFO org.apache.zookeeper.ClientCnxn: Socket
> > connection established to localhost/127.0.0.1:2181, initiating session
> > 2011-06-29 08:26:07,992 INFO org.apache.zookeeper.server.NIOServerCnxn:
> > Client attempting to establish new session at /127.0.0.1:48201
> > 2011-06-29 08:26:07,995 INFO
> > org.apache.zookeeper.server.persistence.FileTxnLog: Creating new log
> file:
> > log.1
> > 2011-06-29 08:26:08,038 INFO org.apache.zookeeper.server.NIOServerCnxn:
> > Established session 0x130db9400860000 with negotiated timeout 40000 for
> > client /127.0.0.1:48201
> > 2011-06-29 08:26:08,038 INFO org.apache.zookeeper.ClientCnxn: Session
> > establishment complete on server localhost/127.0.0.1:2181, sessionid =
> > 0x130db9400860000, negotiated timeout = 40000
> > 2011-06-29 08:26:08,089 INFO org.apache.hadoop.metrics.jvm.JvmMetrics:
> > Initializing JVM Metrics with processName=Master,
> > sessionId=wittgenstein:52888
> > 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics:
> MetricsString
> > added: revision
> > 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics:
> MetricsString
> > added: hdfsUser
> > 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics:
> MetricsString
> > added: hdfsDate
> > 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics:
> MetricsString
> > added: hdfsUrl
> > 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics:
> MetricsString
> > added: date
> > 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics:
> MetricsString
> > added: hdfsRevision
> > 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics:
> MetricsString
> > added: user
> > 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics:
> MetricsString
> > added: hdfsVersion
> > 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics:
> MetricsString
> > added: url
> > 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics:
> MetricsString
> > added: version
> > 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: new
> MBeanInfo
> > 2011-06-29 08:26:08,100 INFO org.apache.hadoop.hbase.metrics: new
> MBeanInfo
> > 2011-06-29 08:26:08,100 INFO
> > org.apache.hadoop.hbase.master.metrics.MasterMetrics: Initialized
> > 2011-06-29 08:26:08,142 INFO org.apache.zookeeper.ZooKeeper: Initiating
> > client connection, connectString=localhost:2181 sessionTimeout=180000
> > watcher=hconnection
> > 2011-06-29 08:26:08,142 INFO org.apache.zookeeper.ClientCnxn: Opening
> socket
> > connection to server localhost/0:0:0:0:0:0:0:1:2181
> > 2011-06-29 08:26:08,142 INFO org.apache.zookeeper.ClientCnxn: Socket
> > connection established to localhost/0:0:0:0:0:0:0:1:2181, initiating
> session
> > 2011-06-29 08:26:08,143 INFO org.apache.zookeeper.server.NIOServerCnxn:
> > Accepted socket connection from /0:0:0:0:0:0:0:1:48008
> > 2011-06-29 08:26:08,143 INFO org.apache.zookeeper.server.NIOServerCnxn:
> > Client attempting to establish new session at /0:0:0:0:0:0:0:1:48008
> > 2011-06-29 08:26:08,152 INFO org.apache.zookeeper.server.NIOServerCnxn:
> > Established session 0x130db9400860001 with negotiated timeout 40000 for
> > client /0:0:0:0:0:0:0:1:48008
> > 2011-06-29 08:26:08,152 INFO org.apache.zookeeper.ClientCnxn: Session
> > establishment complete on server localhost/0:0:0:0:0:0:0:1:2181,
> sessionid =
> > 0x130db9400860001, negotiated timeout = 40000
> > 2011-06-29 08:26:08,154 INFO
> > org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> > KeeperException when processing sessionid:0x130db9400860001 type:create
> > cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> > Path:/hbase Error:KeeperErrorCode = NodeExists for /hbase
> > 2011-06-29 08:26:08,161 INFO
> > org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> > KeeperException when processing sessionid:0x130db9400860001 type:create
> > cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> > Path:/hbase/unassigned Error:KeeperErrorCode = NodeExists for
> > /hbase/unassigned
> > 2011-06-29 08:26:08,169 INFO
> > org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> > KeeperException when processing sessionid:0x130db9400860001 type:create
> > cxid:0x3 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> > Path:/hbase/rs Error:KeeperErrorCode = NodeExists for /hbase/rs
> > 2011-06-29 08:26:08,178 INFO
> > org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> > KeeperException when processing sessionid:0x130db9400860001 type:create
> > cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> > Path:/hbase/table Error:KeeperErrorCode = NodeExists for /hbase/table
> > 2011-06-29 08:26:08,198 INFO org.apache.hadoop.hbase.ipc.HBaseRpcMetrics:
> > Initializing RPC Metrics with hostName=HRegionServer, port=49976
> > 2011-06-29 08:26:08,216 INFO org.apache.zookeeper.ZooKeeper: Initiating
> > client connection, connectString=localhost:2181 sessionTimeout=180000
> > watcher=regionserver:49976
> > 2011-06-29 08:26:08,217 INFO org.apache.zookeeper.ClientCnxn: Opening
> socket
> > connection to server localhost/127.0.0.1:2181
> > 2011-06-29 08:26:08,217 INFO org.apache.zookeeper.ClientCnxn: Socket
> > connection established to localhost/127.0.0.1:2181, initiating session
> > 2011-06-29 08:26:08,217 INFO org.apache.zookeeper.server.NIOServerCnxn:
> > Accepted socket connection from /127.0.0.1:48203
> > 2011-06-29 08:26:08,218 INFO org.apache.zookeeper.server.NIOServerCnxn:
> > Client attempting to establish new session at /127.0.0.1:48203
> > 2011-06-29 08:26:08,228 INFO
> > org.apache.hadoop.hbase.master.ActiveMasterManager:
> > Master=wittgenstein:52888
> > 2011-06-29 08:26:08,235 INFO org.apache.zookeeper.server.NIOServerCnxn:
> > Established session 0x130db9400860002 with negotiated timeout 40000 for
> > client /127.0.0.1:48203
> > 2011-06-29 08:26:08,236 INFO
> > org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> > KeeperException when processing sessionid:0x130db9400860002 type:create
> > cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> > Path:/hbase Error:KeeperErrorCode = NodeExists for /hbase
> > 2011-06-29 08:26:08,246 INFO org.apache.zookeeper.ClientCnxn: Session
> > establishment complete on server localhost/127.0.0.1:2181, sessionid =
> > 0x130db9400860002, negotiated timeout = 40000
> > 2011-06-29 08:26:08,246 INFO
> > org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> > KeeperException when processing sessionid:0x130db9400860002 type:create
> > cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> > Path:/hbase/unassigned Error:KeeperErrorCode = NodeExists for
> > /hbase/unassigned
> > 2011-06-29 08:26:08,252 INFO
> > org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> > KeeperException when processing sessionid:0x130db9400860002 type:create
> > cxid:0x3 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> > Path:/hbase/rs Error:KeeperErrorCode = NodeExists for /hbase/rs
> > 2011-06-29 08:26:08,260 INFO
> > org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> > KeeperException when processing sessionid:0x130db9400860002 type:create
> > cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> > Path:/hbase/table Error:KeeperErrorCode = NodeExists for /hbase/table
> > 2011-06-29 08:26:08,294 INFO org.apache.zookeeper.ZooKeeper: Initiating
> > client connection, connectString=localhost:2181 sessionTimeout=180000
> > watcher=hconnection
> > 2011-06-29 08:26:08,294 INFO org.apache.zookeeper.ClientCnxn: Opening
> socket
> > connection to server localhost/0:0:0:0:0:0:0:1:2181
> > 2011-06-29 08:26:08,294 INFO org.apache.zookeeper.ClientCnxn: Socket
> > connection established to localhost/0:0:0:0:0:0:0:1:2181, initiating
> session
> > 2011-06-29 08:26:08,294 INFO org.apache.zookeeper.server.NIOServerCnxn:
> > Accepted socket connection from /0:0:0:0:0:0:0:1:48010
> > 2011-06-29 08:26:08,294 INFO org.apache.zookeeper.server.NIOServerCnxn:
> > Client attempting to establish new session at /0:0:0:0:0:0:0:1:48010
> > 2011-06-29 08:26:08,302 INFO org.apache.zookeeper.server.NIOServerCnxn:
> > Established session 0x130db9400860003 with negotiated timeout 40000 for
> > client /0:0:0:0:0:0:0:1:48010
> > 2011-06-29 08:26:08,302 INFO org.apache.zookeeper.ClientCnxn: Session
> > establishment complete on server localhost/0:0:0:0:0:0:0:1:2181,
> sessionid =
> > 0x130db9400860003, negotiated timeout = 40000
> > 2011-06-29 08:26:08,302 INFO
> > org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> > KeeperException when processing sessionid:0x130db9400860003 type:create
> > cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> > Path:/hbase Error:KeeperErrorCode = NodeExists for /hbase
> > 2011-06-29 08:26:08,310 INFO
> > org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> > KeeperException when processing sessionid:0x130db9400860003 type:create
> > cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> > Path:/hbase/unassigned Error:KeeperErrorCode = NodeExists for
> > /hbase/unassigned
> > 2011-06-29 08:26:08,319 INFO
> > org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> > KeeperException when processing sessionid:0x130db9400860003 type:create
> > cxid:0x3 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> > Path:/hbase/rs Error:KeeperErrorCode = NodeExists for /hbase/rs
> > 2011-06-29 08:26:08,327 INFO
> > org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> > KeeperException when processing sessionid:0x130db9400860003 type:create
> > cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> > Path:/hbase/table Error:KeeperErrorCode = NodeExists for /hbase/table
> > 2011-06-29 08:26:08,353 DEBUG
> > org.apache.hadoop.hbase.catalog.CatalogTracker: Starting catalog tracker
> > org.apache.hadoop.hbase.catalog.CatalogTracker@3fe2670b
> > 2011-06-29 08:26:08,379 INFO org.apache.hadoop.hbase.master.HMaster:
> Server
> > active/primary master; wittgenstein:52888, sessionid=0x130db9400860000,
> > cluster-up flag was=false
> > 2011-06-29 08:26:08,380 DEBUG
> > org.apache.hadoop.hbase.catalog.CatalogTracker: Starting catalog tracker
> > org.apache.hadoop.hbase.catalog.CatalogTracker@a68cb6b
> > 2011-06-29 08:26:08,383 DEBUG
> > org.apache.hadoop.hbase.executor.ExecutorService: Starting executor
> service
> > name=MASTER_OPEN_REGION-wittgenstein:52888, corePoolSize=5, maxPoolSize=5
> > 2011-06-29 08:26:08,383 DEBUG
> > org.apache.hadoop.hbase.executor.ExecutorService: Starting executor
> service
> > name=MASTER_CLOSE_REGION-wittgenstein:52888, corePoolSize=5,
> maxPoolSize=5
> > 2011-06-29 08:26:08,383 DEBUG
> > org.apache.hadoop.hbase.executor.ExecutorService: Starting executor
> service
> > name=MASTER_SERVER_OPERATIONS-wittgenstein:52888, corePoolSize=3,
> > maxPoolSize=3
> > 2011-06-29 08:26:08,383 DEBUG
> > org.apache.hadoop.hbase.executor.ExecutorService: Starting executor
> service
> > name=MASTER_META_SERVER_OPERATIONS-wittgenstein:52888, corePoolSize=5,
> > maxPoolSize=5
> > 2011-06-29 08:26:08,384 DEBUG
> > org.apache.hadoop.hbase.executor.ExecutorService: Starting executor
> service
> > name=MASTER_TABLE_OPERATIONS-wittgenstein:52888, corePoolSize=1,
> > maxPoolSize=1
> > 2011-06-29 08:26:08,386 DEBUG org.apache.hadoop.hbase.master.LogCleaner:
> Add
> > log cleaner in chain: org.apache.hadoop.hbase.master.TimeToLiveLogCleaner
> > 2011-06-29 08:26:08,389 INFO
> > org.apache.hadoop.hbase.regionserver.MemStoreFlusher:
> > globalMemStoreLimit=396.7m, globalMemStoreLimitLowMark=347.1m,
> > maxHeap=991.7m
> > 2011-06-29 08:26:08,395 DEBUG
> > org.apache.hadoop.hbase.regionserver.PriorityCompactionQueue: Create
> > PriorityCompactionQueue
> > 2011-06-29 08:26:08,396 INFO
> > org.apache.hadoop.hbase.regionserver.HRegionServer: Runs every 10000000ms
> > 2011-06-29 08:26:08,428 INFO
> > org.apache.hadoop.hbase.regionserver.HRegionServer: Attempting connect to
> > Master server at wittgenstein:52888
> > 2011-06-29 08:26:08,503 INFO
> > org.apache.hadoop.hbase.regionserver.HRegionServer: Master isn't
> available
> > yet, retrying
> > 2011-06-29 08:26:08,517 INFO org.mortbay.log: Logging to
> > org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via
> > org.mortbay.log.Slf4jLog
> > 2011-06-29 08:26:08,566 INFO org.apache.hadoop.http.HttpServer: Added
> global
> > filtersafety (class=org.apache.hadoop.http.HttpServer$QuotingInputFilter)
> > 2011-06-29 08:26:08,569 INFO org.apache.hadoop.http.HttpServer: Port
> > returned by webServer.getConnectors()[0].getLocalPort() before open() is
> -1.
> > Opening the listener on 60010
> > 2011-06-29 08:26:08,569 INFO org.apache.hadoop.http.HttpServer:
> > listener.getLocalPort() returned 60010
> > webServer.getConnectors()[0].getLocalPort() returned 60010
> > 2011-06-29 08:26:08,569 INFO org.apache.hadoop.http.HttpServer: Jetty
> bound
> > to port 60010
> > 2011-06-29 08:26:08,569 INFO org.mortbay.log: jetty-6.1.26
> > 2011-06-29 08:26:08,864 INFO org.mortbay.log: Started
> > SelectChannelConnector@0.0.0.0:60010
> > 2011-06-29 08:26:08,864 DEBUG org.apache.hadoop.hbase.master.HMaster:
> > Started service threads
> > 2011-06-29 08:26:10,364 INFO
> org.apache.hadoop.hbase.master.ServerManager:
> > Waiting on regionserver(s) to checkin
> > 2011-06-29 08:26:11,503 INFO
> > org.apache.hadoop.hbase.regionserver.HRegionServer: Attempting connect to
> > Master server at wittgenstein:52888
> > 2011-06-29 08:26:11,505 INFO
> > org.apache.hadoop.hbase.regionserver.HRegionServer: Connected to master
> at
> > wittgenstein:52888
> > 2011-06-29 08:26:11,550 INFO
> > org.apache.hadoop.hbase.regionserver.HRegionServer: Telling master at
> > wittgenstein:52888 that we are up
> > 2011-06-29 08:26:11,553 INFO
> org.apache.hadoop.hbase.master.ServerManager:
> > Registering server=localhost.localdomain,49976,1309353968214,
> regionCount=0,
> > userLoad=false
> > 2011-06-29 08:26:11,564 INFO
> > org.apache.hadoop.hbase.regionserver.HRegionServer: Master passed us
> address
> > to use. Was=wittgenstein:49976, Now=localhost.localdomain:49976
> > 2011-06-29 08:26:11,564 DEBUG
> > org.apache.hadoop.hbase.regionserver.HRegionServer: Config from master:
> > fs.default.name=file:///
> > 2011-06-29 08:26:11,564 DEBUG
> > org.apache.hadoop.hbase.regionserver.HRegionServer: Config from master:
> > hbase.rootdir=file:///tmp/hbase-hbase/hbase
> > 2011-06-29 08:26:11,569 DEBUG
> > org.apache.hadoop.hbase.regionserver.HRegionServer:
> >
> logdir=file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,49976,1309353968214
> > 2011-06-29 08:26:11,572 INFO
> org.apache.hadoop.hbase.regionserver.wal.HLog:
> > HLog configuration: blocksize=32 MB, rollsize=30.4 MB, enabled=true,
> > flushlogentries=1, optionallogflushinternal=1000ms
> > 2011-06-29 08:26:11,603 WARN org.apache.hadoop.util.NativeCodeLoader:
> Unable
> > to load native-hadoop library for your platform... using builtin-java
> > classes where applicable
> > 2011-06-29 08:26:11,610 INFO
> > org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogWriter: syncFs --
> > HDFS-200 -- not available, dfs.support.append=false
> > 2011-06-29 08:26:11,611 INFO
> org.apache.hadoop.hbase.regionserver.wal.HLog:
> > New hlog
> >
> /tmp/hbase-hbase/hbase/.logs/localhost.localdomain,49976,1309353968214/localhost.localdomain%3A49976.1309353971573
> > 2011-06-29 08:26:11,611 INFO
> org.apache.hadoop.hbase.regionserver.wal.HLog:
> > getNumCurrentReplicas--HDFS-826 not available;
> >
> hdfs_out=org.apache.hadoop.fs.ChecksumFileSystem$ChecksumFSOutputSummer@79ca209e
> ,
> >
> exception=org.apache.hadoop.fs.ChecksumFileSystem$ChecksumFSOutputSummer.getNumCurrentReplicas()
> > 2011-06-29 08:26:11,612 INFO org.apache.hadoop.metrics.jvm.JvmMetrics:
> > Cannot initialize JVM Metrics with processName=RegionServer,
> > sessionId=RegionServer:0;wittgenstein,49976,1309353968214 - already
> > initialized
> > 2011-06-29 08:26:11,613 INFO org.apache.hadoop.hbase.metrics: new
> MBeanInfo
> > 2011-06-29 08:26:11,613 INFO
> > org.apache.hadoop.hbase.regionserver.metrics.RegionServerMetrics:
> > Initialized
> > 2011-06-29 08:26:11,614 DEBUG
> > org.apache.hadoop.hbase.executor.ExecutorService: Starting executor
> service
> > name=RS_OPEN_REGION-localhost.localdomain,49976,1309353968214,
> > corePoolSize=3, maxPoolSize=3
> > 2011-06-29 08:26:11,614 DEBUG
> > org.apache.hadoop.hbase.executor.ExecutorService: Starting executor
> service
> > name=RS_OPEN_ROOT-localhost.localdomain,49976,1309353968214,
> corePoolSize=1,
> > maxPoolSize=1
> > 2011-06-29 08:26:11,614 DEBUG
> > org.apache.hadoop.hbase.executor.ExecutorService: Starting executor
> service
> > name=RS_OPEN_META-localhost.localdomain,49976,1309353968214,
> corePoolSize=1,
> > maxPoolSize=1
> > 2011-06-29 08:26:11,614 DEBUG
> > org.apache.hadoop.hbase.executor.ExecutorService: Starting executor
> service
> > name=RS_CLOSE_REGION-localhost.localdomain,49976,1309353968214,
> > corePoolSize=3, maxPoolSize=3
> > 2011-06-29 08:26:11,614 DEBUG
> > org.apache.hadoop.hbase.executor.ExecutorService: Starting executor
> service
> > name=RS_CLOSE_ROOT-localhost.localdomain,49976,1309353968214,
> > corePoolSize=1, maxPoolSize=1
> > 2011-06-29 08:26:11,614 DEBUG
> > org.apache.hadoop.hbase.executor.ExecutorService: Starting executor
> service
> > name=RS_CLOSE_META-localhost.localdomain,49976,1309353968214,
> > corePoolSize=1, maxPoolSize=1
> > 2011-06-29 08:26:11,630 INFO org.apache.hadoop.http.HttpServer: Added
> global
> > filtersafety (class=org.apache.hadoop.http.HttpServer$QuotingInputFilter)
> > 2011-06-29 08:26:11,630 INFO org.apache.hadoop.http.HttpServer: Port
> > returned by webServer.getConnectors()[0].getLocalPort() before open() is
> -1.
> > Opening the listener on 60030
> > 2011-06-29 08:26:11,630 INFO org.apache.hadoop.http.HttpServer:
> > listener.getLocalPort() returned 60030
> > webServer.getConnectors()[0].getLocalPort() returned 60030
> > 2011-06-29 08:26:11,630 INFO org.apache.hadoop.http.HttpServer: Jetty
> bound
> > to port 60030
> > 2011-06-29 08:26:11,630 INFO org.mortbay.log: jetty-6.1.26
> > 2011-06-29 08:26:11,750 INFO org.mortbay.log: Started
> > SelectChannelConnector@0.0.0.0:60030
> > 2011-06-29 08:26:11,757 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > Responder: starting
> > 2011-06-29 08:26:11,758 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > listener on 49976: starting
> > 2011-06-29 08:26:11,758 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 0 on 49976: starting
> > 2011-06-29 08:26:11,758 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 1 on 49976: starting
> > 2011-06-29 08:26:11,759 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 2 on 49976: starting
> > 2011-06-29 08:26:11,759 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 3 on 49976: starting
> > 2011-06-29 08:26:11,759 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 4 on 49976: starting
> > 2011-06-29 08:26:11,759 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 5 on 49976: starting
> > 2011-06-29 08:26:11,759 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 6 on 49976: starting
> > 2011-06-29 08:26:11,759 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 7 on 49976: starting
> > 2011-06-29 08:26:11,760 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 8 on 49976: starting
> > 2011-06-29 08:26:11,760 INFO org.apache.hadoop.ipc.HBaseServer: IPC
> Server
> > handler 9 on 49976: starting
> > 2011-06-29 08:26:11,760 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> > Server handler 0 on 49976: starting
> > 2011-06-29 08:26:11,760 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> > Server handler 1 on 49976: starting
> > 2011-06-29 08:26:11,760 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> > Server handler 2 on 49976: starting
> > 2011-06-29 08:26:11,761 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> > Server handler 3 on 49976: starting
> > 2011-06-29 08:26:11,761 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> > Server handler 4 on 49976: starting
> > 2011-06-29 08:26:11,761 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> > Server handler 5 on 49976: starting
> > 2011-06-29 08:26:11,761 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> > Server handler 6 on 49976: starting
> > 2011-06-29 08:26:11,761 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> > Server handler 7 on 49976: starting
> > 2011-06-29 08:26:11,762 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> > Server handler 8 on 49976: starting
> > 2011-06-29 08:26:11,762 INFO
> > org.apache.hadoop.hbase.regionserver.HRegionServer: Serving as
> > localhost.localdomain,49976,1309353968214, RPC listening on /
> 127.0.1.1:49976,
> > sessionid=0x130db9400860002
> > 2011-06-29 08:26:11,763 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> > Server handler 9 on 49976: starting
> > 2011-06-29 08:26:11,766 INFO
> org.apache.hadoop.hbase.regionserver.StoreFile:
> > Allocating LruBlockCache with maximum size 198.3m
> > 2011-06-29 08:26:11,865 INFO
> org.apache.hadoop.hbase.master.ServerManager:
> > Waiting on regionserver(s) count to settle; currently=1
> > 2011-06-29 08:26:13,365 INFO
> org.apache.hadoop.hbase.master.ServerManager:
> > Finished waiting for regionserver count to settle; count=1, sleptFor=4500
> > 2011-06-29 08:26:13,366 INFO
> org.apache.hadoop.hbase.master.ServerManager:
> > Exiting wait on regionserver(s) to checkin; count=1, stopped=false, count
> of
> > regions out on cluster=0
> > 2011-06-29 08:26:13,368 INFO
> > org.apache.hadoop.hbase.master.MasterFileSystem: Log folder
> >
> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,49976,1309353968214
> > belongs to an existing region server
> > 2011-06-29 08:26:13,368 INFO
> > org.apache.hadoop.hbase.master.MasterFileSystem: Log folder
> >
> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764
> > doesn't belong to a known region server, splitting
> > 2011-06-29 08:26:13,381 INFO
> > org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Splitting 1
> hlog(s)
> > in
> >
> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764
> > 2011-06-29 08:26:13,382 DEBUG
> > org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Writer thread
> > Thread[WriterThread-0,5,main]: starting
> > 2011-06-29 08:26:13,387 DEBUG
> > org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Writer thread
> > Thread[WriterThread-1,5,main]: starting
> > 2011-06-29 08:26:13,388 DEBUG
> > org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Writer thread
> > Thread[WriterThread-2,5,main]: starting
> > 2011-06-29 08:26:13,388 DEBUG
> > org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Splitting hlog 1
> of
> > 1:
> >
> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764/localhost.localdomain%3A38896.1309353525054,
> > length=0
> > 2011-06-29 08:26:13,389 WARN org.apache.hadoop.hbase.util.FSUtils:
> Running
> > on HDFS without append enabled may result in data loss
> > 2011-06-29 08:26:13,389 WARN
> > org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: File
> >
> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764/localhost.localdomain%3A38896.1309353525054
> > might be still open, length is 0
> > 2011-06-29 08:26:13,399 WARN org.apache.hadoop.fs.FSInputChecker: Problem
> > opening checksum file:
> >
> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764/localhost.localdomain%3A38896.1309353525054.
> >  Ignoring exception: java.io.EOFException
> > at java.io.DataInputStream.readFully(DataInputStream.java:180)
> > at java.io.DataInputStream.readFully(DataInputStream.java:152)
> > at
> >
> org.apache.hadoop.fs.ChecksumFileSystem$ChecksumFSInputChecker.<init>(ChecksumFileSystem.java:135)
> > at
> org.apache.hadoop.fs.ChecksumFileSystem.open(ChecksumFileSystem.java:284)
> > at
> org.apache.hadoop.io.SequenceFile$Reader.openFile(SequenceFile.java:1452)
> > at
> >
> org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader$WALReader.openFile(SequenceFileLogReader.java:65)
> > at
> org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1431)
> > at
> org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1424)
> > at
> org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1419)
> > at
> >
> org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader$WALReader.<init>(SequenceFileLogReader.java:57)
> > at
> >
> org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader.init(SequenceFileLogReader.java:158)
> > at org.apache.hadoop.hbase.regionserver.wal.HLog.getReader(HLog.java:576)
> > at
> >
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.getReader(HLogSplitter.java:477)
> > at
> >
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.parseHLog(HLogSplitter.java:414)
> > at
> >
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.splitLog(HLogSplitter.java:262)
> > at
> >
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.splitLog(HLogSplitter.java:188)
> > at
> >
> org.apache.hadoop.hbase.master.MasterFileSystem.splitLog(MasterFileSystem.java:197)
> > at
> >
> org.apache.hadoop.hbase.master.MasterFileSystem.splitLogAfterStartup(MasterFileSystem.java:181)
> > at
> >
> org.apache.hadoop.hbase.master.HMaster.finishInitialization(HMaster.java:384)
> > at org.apache.hadoop.hbase.master.HMaster.run(HMaster.java:283)
> > at
> >
> org.apache.hadoop.hbase.master.HMasterCommandLine$LocalHMaster.run(HMasterCommandLine.java:193)
> > at java.lang.Thread.run(Thread.java:662)
> >
> > 2011-06-29 08:26:13,402 WARN
> > org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader: Error
> while
> > trying to get accurate file length.  Truncation / data loss may occur if
> > RegionServers die.
> > java.lang.NoSuchMethodException:
> >
> org.apache.hadoop.fs.ChecksumFileSystem$ChecksumFSInputChecker.getFileLength()
> > at java.lang.Class.getMethod(Class.java:1605)
> > at
> >
> org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader$WALReader$WALReaderFSDataInputStream.getPos(SequenceFileLogReader.java:107)
> > at
> org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1436)
> > at
> org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1424)
> > at
> org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1419)
> > at
> >
> org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader$WALReader.<init>(SequenceFileLogReader.java:57)
> > at
> >
> org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader.init(SequenceFileLogReader.java:158)
> > at org.apache.hadoop.hbase.regionserver.wal.HLog.getReader(HLog.java:576)
> > at
> >
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.getReader(HLogSplitter.java:477)
> > at
> >
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.parseHLog(HLogSplitter.java:414)
> > at
> >
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.splitLog(HLogSplitter.java:262)
> > at
> >
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.splitLog(HLogSplitter.java:188)
> > at
> >
> org.apache.hadoop.hbase.master.MasterFileSystem.splitLog(MasterFileSystem.java:197)
> > at
> >
> org.apache.hadoop.hbase.master.MasterFileSystem.splitLogAfterStartup(MasterFileSystem.java:181)
> > at
> >
> org.apache.hadoop.hbase.master.HMaster.finishInitialization(HMaster.java:384)
> > at org.apache.hadoop.hbase.master.HMaster.run(HMaster.java:283)
> > at
> >
> org.apache.hadoop.hbase.master.HMasterCommandLine$LocalHMaster.run(HMasterCommandLine.java:193)
> > at java.lang.Thread.run(Thread.java:662)
> > 2011-06-29 08:26:13,405 WARN
> > org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Could not open
> >
> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764/localhost.localdomain%3A38896.1309353525054
> > for reading. File is emptyjava.io.EOFException
> > 2011-06-29 08:26:13,408 INFO
> > org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Archived processed
> > log
> >
> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764/localhost.localdomain%3A38896.1309353525054
> > to
> >
> file:/tmp/hbase-hbase/hbase/.oldlogs/localhost.localdomain%3A38896.1309353525054
> > 2011-06-29 08:26:13,409 INFO
> > org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Waiting for split
> > writer threads to finish
> > 2011-06-29 08:26:14,388 INFO
> > org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Split writers
> > finished
> > 2011-06-29 08:26:14,389 INFO
> > org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: hlog file
> splitting
> > completed in 1010 ms for
> >
> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764
> > 2011-06-29 08:26:15,394 INFO
> > org.apache.hadoop.hbase.catalog.RootLocationEditor: Unsetting ROOT region
> > location in ZooKeeper
> > 2011-06-29 08:26:15,396 INFO
> > org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> > KeeperException when processing sessionid:0x130db9400860000 type:delete
> > cxid:0x11 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> > Path:/hbase/root-region-server Error:KeeperErrorCode = NoNode for
> > /hbase/root-region-server
> > 2011-06-29 08:26:15,423 DEBUG org.apache.hadoop.hbase.zookeeper.ZKAssign:
> > master:52888-0x130db9400860000 Creating (or updating) unassigned node for
> > 70236052 with OFFLINE state
> > 2011-06-29 08:26:15,440 DEBUG
> > org.apache.hadoop.hbase.master.AssignmentManager: No previous transition
> > plan was found (or we are ignoring an existing plan) for
> -ROOT-,,0.70236052
> > so generated a random one; hri=-ROOT-,,0.70236052, src=,
> > dest=localhost.localdomain,49976,1309353968214; 1 (online=1,
> exclude=null)
> > available servers
> > 2011-06-29 08:26:15,440 DEBUG
> > org.apache.hadoop.hbase.master.AssignmentManager: Assigning region
> > -ROOT-,,0.70236052 to localhost.localdomain,49976,1309353968214
> > 2011-06-29 08:26:15,440 DEBUG
> org.apache.hadoop.hbase.master.ServerManager:
> > New connection to localhost.localdomain,49976,1309353968214
> > 2011-06-29 08:26:15,462 INFO org.apache.hadoop.ipc.HbaseRPC: Server at /
> > 127.0.0.1:49976 could not be reached after 1 tries, giving up.
> > 2011-06-29 08:26:15,463 WARN
> > org.apache.hadoop.hbase.master.AssignmentManager: Failed assignment of
> > -ROOT-,,0.70236052 to
> serverName=localhost.localdomain,49976,1309353968214,
> > load=(requests=0, regions=0, usedHeap=31, maxHeap=991), trying to assign
> > elsewhere instead; retry=0
> > org.apache.hadoop.hbase.client.RetriesExhaustedException: Failed setting
> up
> > proxy interface org.apache.hadoop.hbase.ipc.HRegionInterface to /
> > 127.0.0.1:49976 after attempts=1
> > at org.apache.hadoop.hbase.ipc.HBaseRPC.waitForProxy(HBaseRPC.java:355)
> > at
> >
> org.apache.hadoop.hbase.client.HConnectionManager$HConnectionImplementation.getHRegionConnection(HConnectionManager.java:957)
> > at
> >
> org.apache.hadoop.hbase.master.ServerManager.getServerConnection(ServerManager.java:606)
> > at
> >
> org.apache.hadoop.hbase.master.ServerManager.sendRegionOpen(ServerManager.java:541)
> > at
> >
> org.apache.hadoop.hbase.master.AssignmentManager.assign(AssignmentManager.java:901)
> > at
> >
> org.apache.hadoop.hbase.master.AssignmentManager.assign(AssignmentManager.java:730)
> > at
> >
> org.apache.hadoop.hbase.master.AssignmentManager.assign(AssignmentManager.java:710)
> > at
> >
> org.apache.hadoop.hbase.master.AssignmentManager.assignRoot(AssignmentManager.java:1170)
> > at
> >
> org.apache.hadoop.hbase.master.HMaster.assignRootAndMeta(HMaster.java:431)
> > at
> >
> org.apache.hadoop.hbase.master.HMaster.finishInitialization(HMaster.java:388)
> > at org.apache.hadoop.hbase.master.HMaster.run(HMaster.java:283)
> > at
> >
> org.apache.hadoop.hbase.master.HMasterCommandLine$LocalHMaster.run(HMasterCommandLine.java:193)
> > at java.lang.Thread.run(Thread.java:662)
> > Caused by: java.net.ConnectException: Connection refused
> > at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
> > at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:567)
> > at
> >
> org.apache.hadoop.net.SocketIOWithTimeout.connect(SocketIOWithTimeout.java:206)
> > at org.apache.hadoop.net.NetUtils.connect(NetUtils.java:408)
> > at
> >
> org.apache.hadoop.hbase.ipc.HBaseClient$Connection.setupIOstreams(HBaseClient.java:328)
> > at
> >
> org.apache.hadoop.hbase.ipc.HBaseClient.getConnection(HBaseClient.java:883)
> > at org.apache.hadoop.hbase.ipc.HBaseClient.call(HBaseClient.java:750)
> > at org.apache.hadoop.hbase.ipc.HBaseRPC$Invoker.invoke(HBaseRPC.java:257)
> > at $Proxy7.getProtocolVersion(Unknown Source)
> > at org.apache.hadoop.hbase.ipc.HBaseRPC.getProxy(HBaseRPC.java:419)
> > at org.apache.hadoop.hbase.ipc.HBaseRPC.getProxy(HBaseRPC.java:393)
> > at org.apache.hadoop.hbase.ipc.HBaseRPC.getProxy(HBaseRPC.java:444)
> > at org.apache.hadoop.hbase.ipc.HBaseRPC.waitForProxy(HBaseRPC.java:349)
> > ... 12 more
> > 2011-06-29 08:26:15,463 WARN
> > org.apache.hadoop.hbase.master.AssignmentManager: Unable to find a viable
> > location to assign region -ROOT-,,0.70236052
> >
> > 127.0.0.1 localhost.localdomain localhost
> > ::1 wittgenstein localhost6.localdomain6 localhost6
> > 127.0.1.1 wittgenstein
> >
> > # The following lines are desirable for IPv6 capable hosts
> > ::1     localhost ip6-localhost ip6-loopback
> > fe00::0 ip6-localnet
> > ff00::0 ip6-mcastprefix
> > ff02::1 ip6-allnodes
> > ff02::2 ip6-allrouters
> > ff02::3 ip6-allhosts
> >
>

Re: problem starting standalone hbase on Ubuntu 11.04

[Stack <st...@duboce.net>]

Can you fix this:

2011-06-29 08:26:11,762 INFO
org.apache.hadoop.hbase.regionserver.HRegionServer: Serving as
localhost.localdomain,49976,1309353968214, RPC listening on
/127.0.1.1:49976, sessionid=0x130db9400860002

Make it so servers are 127.0.0.1 or localhost.localdomain, not both.

St.Ack

On Wed, Jun 29, 2011 at 6:58 AM, Blake Lemoine <ba...@gmail.com> wrote:
> Hello,
>
>   I'm having problems getting HBase up and running on my Ubuntu 11.04
> installation.  I'm not really certain what's wrong but many of the posts of
> similar issues I'm finding reference changes to /etc/hosts .  Unfortunately
> the lines that those suggestions say to delete are not in my hosts file.
>  Below is a copy of the log as well as a copy of my /etc/hosts file.
>
> Wed Jun 29 08:26:07 CDT 2011 Starting master on wittgenstein
> ulimit -n 1024
> 2011-06-29 08:26:07,713 INFO org.apache.zookeeper.server.ZooKeeperServer:
> Server environment:zookeeper.version=3.3.3-cdh3u0--1, built on 03/26/2011
> 00:20 GMT
> 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> Server environment:host.name=wittgenstein
> 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> Server environment:java.version=1.6.0_24
> 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> Server environment:java.vendor=Sun Microsystems Inc.
> 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> Server environment:java.home=/usr/lib/jvm/java-6-sun-1.6.0.24/jre
> 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> Server
> environment:java.class.path=/usr/lib/hbase/conf:/usr/lib/jvm/java-6-sun/lib/tools.jar:/usr/lib/hbase:/usr/lib/hbase/hbase-0.90.1-cdh3u0.jar:/usr/lib/hbase/hbase-0.90.1-cdh3u0-tests.jar:/usr/lib/hbase/lib/activation-1.1.jar:/usr/lib/hbase/lib/asm-3.1.jar:/usr/lib/hbase/lib/avro-1.3.3.jar:/usr/lib/hbase/lib/commons-cli-1.2.jar:/usr/lib/hbase/lib/commons-codec-1.4.jar:/usr/lib/hbase/lib/commons-el-1.0.jar:/usr/lib/hbase/lib/commons-httpclient-3.1.jar:/usr/lib/hbase/lib/commons-lang-2.5.jar:/usr/lib/hbase/lib/commons-logging-1.1.1.jar:/usr/lib/hbase/lib/commons-net-1.4.1.jar:/usr/lib/hbase/lib/core-3.1.1.jar:/usr/lib/hbase/lib/guava-r06.jar:/usr/lib/hbase/lib/hadoop-core.jar:/usr/lib/hbase/lib/hbase-0.90.1-cdh3u0.jar:/usr/lib/hbase/lib/jackson-core-asl-1.5.2.jar:/usr/lib/hbase/lib/jackson-jaxrs-1.5.5.jar:/usr/lib/hbase/lib/jackson-mapper-asl-1.5.2.jar:/usr/lib/hbase/lib/jackson-xc-1.5.5.jar:/usr/lib/hbase/lib/jasper-compiler-5.5.23.jar:/usr/lib/hbase/lib/jasper-runtime-5.5.23.jar:/usr/lib/hbase/lib/jaxb-api-2.1.jar:/usr/lib/hbase/lib/jaxb-impl-2.1.12.jar:/usr/lib/hbase/lib/jersey-core-1.4.jar:/usr/lib/hbase/lib/jersey-json-1.4.jar:/usr/lib/hbase/lib/jersey-server-1.4.jar:/usr/lib/hbase/lib/jettison-1.1.jar:/usr/lib/hbase/lib/jetty-6.1.26.jar:/usr/lib/hbase/lib/jetty-util-6.1.26.jar:/usr/lib/hbase/lib/jruby-complete-1.0.3.jar:/usr/lib/hbase/lib/jsp-2.1-6.1.14.jar:/usr/lib/hbase/lib/jsp-api-2.1-6.1.14.jar:/usr/lib/hbase/lib/jsp-api-2.1.jar:/usr/lib/hbase/lib/jsr311-api-1.1.1.jar:/usr/lib/hbase/lib/log4j-1.2.16.jar:/usr/lib/hbase/lib/protobuf-java-2.3.0.jar:/usr/lib/hbase/lib/servlet-api-2.5-6.1.14.jar:/usr/lib/hbase/lib/servlet-api-2.5.jar:/usr/lib/hbase/lib/slf4j-api-1.5.8.jar:/usr/lib/hbase/lib/slf4j-log4j12-1.5.8.jar:/usr/lib/hbase/lib/stax-api-1.0.1.jar:/usr/lib/hbase/lib/thrift-0.2.0.jar:/usr/lib/hbase/lib/xmlenc-0.52.jar:/usr/lib/hbase/lib/zookeeper.jar
> 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> Server
> environment:java.library.path=/usr/lib/jvm/java-6-sun-1.6.0.24/jre/lib/amd64/server:/usr/lib/jvm/java-6-sun-1.6.0.24/jre/lib/amd64:/usr/lib/jvm/java-6-sun-1.6.0.24/jre/../lib/amd64:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
> 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> Server environment:java.io.tmpdir=/tmp
> 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> Server environment:java.compiler=<NA>
> 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> Server environment:os.name=Linux
> 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> Server environment:os.arch=amd64
> 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> Server environment:os.version=2.6.38-8-generic
> 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> Server environment:user.name=hbase
> 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> Server environment:user.home=/var/lib/hbase
> 2011-06-29 08:26:07,714 INFO org.apache.zookeeper.server.ZooKeeperServer:
> Server environment:user.dir=/var/log/hbase
> 2011-06-29 08:26:07,725 INFO org.apache.zookeeper.server.ZooKeeperServer:
> Created server with tickTime 2000 minSessionTimeout 4000 maxSessionTimeout
> 40000 datadir /tmp/hbase-hbase/zookeeper/zookeeper/version-2 snapdir
> /tmp/hbase-hbase/zookeeper/zookeeper/version-2
> 2011-06-29 08:26:07,741 INFO org.apache.zookeeper.server.NIOServerCnxn:
> binding to port 0.0.0.0/0.0.0.0:2181
> 2011-06-29 08:26:07,745 INFO
> org.apache.zookeeper.server.persistence.FileTxnSnapLog: Snapshotting: 0
> 2011-06-29 08:26:07,793 INFO org.apache.zookeeper.server.NIOServerCnxn:
> Accepted socket connection from /127.0.0.1:48200
> 2011-06-29 08:26:07,796 INFO org.apache.zookeeper.server.NIOServerCnxn:
> Processing stat command from /127.0.0.1:48200
> 2011-06-29 08:26:07,802 INFO org.apache.zookeeper.server.NIOServerCnxn: Stat
> command output
> 2011-06-29 08:26:07,802 INFO org.apache.zookeeper.server.NIOServerCnxn:
> Closed socket connection for client /127.0.0.1:48200 (no session established
> for client)
> 2011-06-29 08:26:07,803 INFO
> org.apache.hadoop.hbase.zookeeper.MiniZooKeeperCluster: Started MiniZK
> Server on client port: 2181
> 2011-06-29 08:26:07,841 INFO org.apache.hadoop.hbase.ipc.HBaseRpcMetrics:
> Initializing RPC Metrics with hostName=HMasterCommandLine$LocalHMaster,
> port=52888
> 2011-06-29 08:26:07,968 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> Responder: starting
> 2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> listener on 52888: starting
> 2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 0 on 52888: starting
> 2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 1 on 52888: starting
> 2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 2 on 52888: starting
> 2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 3 on 52888: starting
> 2011-06-29 08:26:07,970 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 6 on 52888: starting
> 2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 5 on 52888: starting
> 2011-06-29 08:26:07,969 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 4 on 52888: starting
> 2011-06-29 08:26:07,970 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 7 on 52888: starting
> 2011-06-29 08:26:07,970 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 8 on 52888: starting
> 2011-06-29 08:26:07,977 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 9 on 52888: starting
> 2011-06-29 08:26:07,979 INFO org.apache.zookeeper.ZooKeeper: Client
> environment:zookeeper.version=3.3.3-cdh3u0--1, built on 03/26/2011 00:20 GMT
> 2011-06-29 08:26:07,979 INFO org.apache.zookeeper.ZooKeeper: Client
> environment:host.name=wittgenstein
> 2011-06-29 08:26:07,979 INFO org.apache.zookeeper.ZooKeeper: Client
> environment:java.version=1.6.0_24
> 2011-06-29 08:26:07,979 INFO org.apache.zookeeper.ZooKeeper: Client
> environment:java.vendor=Sun Microsystems Inc.
> 2011-06-29 08:26:07,979 INFO org.apache.zookeeper.ZooKeeper: Client
> environment:java.home=/usr/lib/jvm/java-6-sun-1.6.0.24/jre
> 2011-06-29 08:26:07,979 INFO org.apache.zookeeper.ZooKeeper: Client
> environment:java.class.path=/usr/lib/hbase/conf:/usr/lib/jvm/java-6-sun/lib/tools.jar:/usr/lib/hbase:/usr/lib/hbase/hbase-0.90.1-cdh3u0.jar:/usr/lib/hbase/hbase-0.90.1-cdh3u0-tests.jar:/usr/lib/hbase/lib/activation-1.1.jar:/usr/lib/hbase/lib/asm-3.1.jar:/usr/lib/hbase/lib/avro-1.3.3.jar:/usr/lib/hbase/lib/commons-cli-1.2.jar:/usr/lib/hbase/lib/commons-codec-1.4.jar:/usr/lib/hbase/lib/commons-el-1.0.jar:/usr/lib/hbase/lib/commons-httpclient-3.1.jar:/usr/lib/hbase/lib/commons-lang-2.5.jar:/usr/lib/hbase/lib/commons-logging-1.1.1.jar:/usr/lib/hbase/lib/commons-net-1.4.1.jar:/usr/lib/hbase/lib/core-3.1.1.jar:/usr/lib/hbase/lib/guava-r06.jar:/usr/lib/hbase/lib/hadoop-core.jar:/usr/lib/hbase/lib/hbase-0.90.1-cdh3u0.jar:/usr/lib/hbase/lib/jackson-core-asl-1.5.2.jar:/usr/lib/hbase/lib/jackson-jaxrs-1.5.5.jar:/usr/lib/hbase/lib/jackson-mapper-asl-1.5.2.jar:/usr/lib/hbase/lib/jackson-xc-1.5.5.jar:/usr/lib/hbase/lib/jasper-compiler-5.5.23.jar:/usr/lib/hbase/lib/jasper-runtime-5.5.23.jar:/usr/lib/hbase/lib/jaxb-api-2.1.jar:/usr/lib/hbase/lib/jaxb-impl-2.1.12.jar:/usr/lib/hbase/lib/jersey-core-1.4.jar:/usr/lib/hbase/lib/jersey-json-1.4.jar:/usr/lib/hbase/lib/jersey-server-1.4.jar:/usr/lib/hbase/lib/jettison-1.1.jar:/usr/lib/hbase/lib/jetty-6.1.26.jar:/usr/lib/hbase/lib/jetty-util-6.1.26.jar:/usr/lib/hbase/lib/jruby-complete-1.0.3.jar:/usr/lib/hbase/lib/jsp-2.1-6.1.14.jar:/usr/lib/hbase/lib/jsp-api-2.1-6.1.14.jar:/usr/lib/hbase/lib/jsp-api-2.1.jar:/usr/lib/hbase/lib/jsr311-api-1.1.1.jar:/usr/lib/hbase/lib/log4j-1.2.16.jar:/usr/lib/hbase/lib/protobuf-java-2.3.0.jar:/usr/lib/hbase/lib/servlet-api-2.5-6.1.14.jar:/usr/lib/hbase/lib/servlet-api-2.5.jar:/usr/lib/hbase/lib/slf4j-api-1.5.8.jar:/usr/lib/hbase/lib/slf4j-log4j12-1.5.8.jar:/usr/lib/hbase/lib/stax-api-1.0.1.jar:/usr/lib/hbase/lib/thrift-0.2.0.jar:/usr/lib/hbase/lib/xmlenc-0.52.jar:/usr/lib/hbase/lib/zookeeper.jar
> 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
> environment:java.library.path=/usr/lib/jvm/java-6-sun-1.6.0.24/jre/lib/amd64/server:/usr/lib/jvm/java-6-sun-1.6.0.24/jre/lib/amd64:/usr/lib/jvm/java-6-sun-1.6.0.24/jre/../lib/amd64:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
> 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
> environment:java.io.tmpdir=/tmp
> 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
> environment:java.compiler=<NA>
> 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
> environment:os.name=Linux
> 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
> environment:os.arch=amd64
> 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
> environment:os.version=2.6.38-8-generic
> 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
> environment:user.name=hbase
> 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
> environment:user.home=/var/lib/hbase
> 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Client
> environment:user.dir=/var/log/hbase
> 2011-06-29 08:26:07,980 INFO org.apache.zookeeper.ZooKeeper: Initiating
> client connection, connectString=localhost:2181 sessionTimeout=180000
> watcher=master:52888
> 2011-06-29 08:26:07,988 INFO org.apache.zookeeper.ClientCnxn: Opening socket
> connection to server localhost/127.0.0.1:2181
> 2011-06-29 08:26:07,988 INFO org.apache.zookeeper.server.NIOServerCnxn:
> Accepted socket connection from /127.0.0.1:48201
> 2011-06-29 08:26:07,989 INFO org.apache.zookeeper.ClientCnxn: Socket
> connection established to localhost/127.0.0.1:2181, initiating session
> 2011-06-29 08:26:07,992 INFO org.apache.zookeeper.server.NIOServerCnxn:
> Client attempting to establish new session at /127.0.0.1:48201
> 2011-06-29 08:26:07,995 INFO
> org.apache.zookeeper.server.persistence.FileTxnLog: Creating new log file:
> log.1
> 2011-06-29 08:26:08,038 INFO org.apache.zookeeper.server.NIOServerCnxn:
> Established session 0x130db9400860000 with negotiated timeout 40000 for
> client /127.0.0.1:48201
> 2011-06-29 08:26:08,038 INFO org.apache.zookeeper.ClientCnxn: Session
> establishment complete on server localhost/127.0.0.1:2181, sessionid =
> 0x130db9400860000, negotiated timeout = 40000
> 2011-06-29 08:26:08,089 INFO org.apache.hadoop.metrics.jvm.JvmMetrics:
> Initializing JVM Metrics with processName=Master,
> sessionId=wittgenstein:52888
> 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
> added: revision
> 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
> added: hdfsUser
> 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
> added: hdfsDate
> 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
> added: hdfsUrl
> 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
> added: date
> 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
> added: hdfsRevision
> 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
> added: user
> 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
> added: hdfsVersion
> 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
> added: url
> 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: MetricsString
> added: version
> 2011-06-29 08:26:08,099 INFO org.apache.hadoop.hbase.metrics: new MBeanInfo
> 2011-06-29 08:26:08,100 INFO org.apache.hadoop.hbase.metrics: new MBeanInfo
> 2011-06-29 08:26:08,100 INFO
> org.apache.hadoop.hbase.master.metrics.MasterMetrics: Initialized
> 2011-06-29 08:26:08,142 INFO org.apache.zookeeper.ZooKeeper: Initiating
> client connection, connectString=localhost:2181 sessionTimeout=180000
> watcher=hconnection
> 2011-06-29 08:26:08,142 INFO org.apache.zookeeper.ClientCnxn: Opening socket
> connection to server localhost/0:0:0:0:0:0:0:1:2181
> 2011-06-29 08:26:08,142 INFO org.apache.zookeeper.ClientCnxn: Socket
> connection established to localhost/0:0:0:0:0:0:0:1:2181, initiating session
> 2011-06-29 08:26:08,143 INFO org.apache.zookeeper.server.NIOServerCnxn:
> Accepted socket connection from /0:0:0:0:0:0:0:1:48008
> 2011-06-29 08:26:08,143 INFO org.apache.zookeeper.server.NIOServerCnxn:
> Client attempting to establish new session at /0:0:0:0:0:0:0:1:48008
> 2011-06-29 08:26:08,152 INFO org.apache.zookeeper.server.NIOServerCnxn:
> Established session 0x130db9400860001 with negotiated timeout 40000 for
> client /0:0:0:0:0:0:0:1:48008
> 2011-06-29 08:26:08,152 INFO org.apache.zookeeper.ClientCnxn: Session
> establishment complete on server localhost/0:0:0:0:0:0:0:1:2181, sessionid =
> 0x130db9400860001, negotiated timeout = 40000
> 2011-06-29 08:26:08,154 INFO
> org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> KeeperException when processing sessionid:0x130db9400860001 type:create
> cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> Path:/hbase Error:KeeperErrorCode = NodeExists for /hbase
> 2011-06-29 08:26:08,161 INFO
> org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> KeeperException when processing sessionid:0x130db9400860001 type:create
> cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> Path:/hbase/unassigned Error:KeeperErrorCode = NodeExists for
> /hbase/unassigned
> 2011-06-29 08:26:08,169 INFO
> org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> KeeperException when processing sessionid:0x130db9400860001 type:create
> cxid:0x3 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> Path:/hbase/rs Error:KeeperErrorCode = NodeExists for /hbase/rs
> 2011-06-29 08:26:08,178 INFO
> org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> KeeperException when processing sessionid:0x130db9400860001 type:create
> cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> Path:/hbase/table Error:KeeperErrorCode = NodeExists for /hbase/table
> 2011-06-29 08:26:08,198 INFO org.apache.hadoop.hbase.ipc.HBaseRpcMetrics:
> Initializing RPC Metrics with hostName=HRegionServer, port=49976
> 2011-06-29 08:26:08,216 INFO org.apache.zookeeper.ZooKeeper: Initiating
> client connection, connectString=localhost:2181 sessionTimeout=180000
> watcher=regionserver:49976
> 2011-06-29 08:26:08,217 INFO org.apache.zookeeper.ClientCnxn: Opening socket
> connection to server localhost/127.0.0.1:2181
> 2011-06-29 08:26:08,217 INFO org.apache.zookeeper.ClientCnxn: Socket
> connection established to localhost/127.0.0.1:2181, initiating session
> 2011-06-29 08:26:08,217 INFO org.apache.zookeeper.server.NIOServerCnxn:
> Accepted socket connection from /127.0.0.1:48203
> 2011-06-29 08:26:08,218 INFO org.apache.zookeeper.server.NIOServerCnxn:
> Client attempting to establish new session at /127.0.0.1:48203
> 2011-06-29 08:26:08,228 INFO
> org.apache.hadoop.hbase.master.ActiveMasterManager:
> Master=wittgenstein:52888
> 2011-06-29 08:26:08,235 INFO org.apache.zookeeper.server.NIOServerCnxn:
> Established session 0x130db9400860002 with negotiated timeout 40000 for
> client /127.0.0.1:48203
> 2011-06-29 08:26:08,236 INFO
> org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> KeeperException when processing sessionid:0x130db9400860002 type:create
> cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> Path:/hbase Error:KeeperErrorCode = NodeExists for /hbase
> 2011-06-29 08:26:08,246 INFO org.apache.zookeeper.ClientCnxn: Session
> establishment complete on server localhost/127.0.0.1:2181, sessionid =
> 0x130db9400860002, negotiated timeout = 40000
> 2011-06-29 08:26:08,246 INFO
> org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> KeeperException when processing sessionid:0x130db9400860002 type:create
> cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> Path:/hbase/unassigned Error:KeeperErrorCode = NodeExists for
> /hbase/unassigned
> 2011-06-29 08:26:08,252 INFO
> org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> KeeperException when processing sessionid:0x130db9400860002 type:create
> cxid:0x3 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> Path:/hbase/rs Error:KeeperErrorCode = NodeExists for /hbase/rs
> 2011-06-29 08:26:08,260 INFO
> org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> KeeperException when processing sessionid:0x130db9400860002 type:create
> cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> Path:/hbase/table Error:KeeperErrorCode = NodeExists for /hbase/table
> 2011-06-29 08:26:08,294 INFO org.apache.zookeeper.ZooKeeper: Initiating
> client connection, connectString=localhost:2181 sessionTimeout=180000
> watcher=hconnection
> 2011-06-29 08:26:08,294 INFO org.apache.zookeeper.ClientCnxn: Opening socket
> connection to server localhost/0:0:0:0:0:0:0:1:2181
> 2011-06-29 08:26:08,294 INFO org.apache.zookeeper.ClientCnxn: Socket
> connection established to localhost/0:0:0:0:0:0:0:1:2181, initiating session
> 2011-06-29 08:26:08,294 INFO org.apache.zookeeper.server.NIOServerCnxn:
> Accepted socket connection from /0:0:0:0:0:0:0:1:48010
> 2011-06-29 08:26:08,294 INFO org.apache.zookeeper.server.NIOServerCnxn:
> Client attempting to establish new session at /0:0:0:0:0:0:0:1:48010
> 2011-06-29 08:26:08,302 INFO org.apache.zookeeper.server.NIOServerCnxn:
> Established session 0x130db9400860003 with negotiated timeout 40000 for
> client /0:0:0:0:0:0:0:1:48010
> 2011-06-29 08:26:08,302 INFO org.apache.zookeeper.ClientCnxn: Session
> establishment complete on server localhost/0:0:0:0:0:0:0:1:2181, sessionid =
> 0x130db9400860003, negotiated timeout = 40000
> 2011-06-29 08:26:08,302 INFO
> org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> KeeperException when processing sessionid:0x130db9400860003 type:create
> cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> Path:/hbase Error:KeeperErrorCode = NodeExists for /hbase
> 2011-06-29 08:26:08,310 INFO
> org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> KeeperException when processing sessionid:0x130db9400860003 type:create
> cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> Path:/hbase/unassigned Error:KeeperErrorCode = NodeExists for
> /hbase/unassigned
> 2011-06-29 08:26:08,319 INFO
> org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> KeeperException when processing sessionid:0x130db9400860003 type:create
> cxid:0x3 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> Path:/hbase/rs Error:KeeperErrorCode = NodeExists for /hbase/rs
> 2011-06-29 08:26:08,327 INFO
> org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> KeeperException when processing sessionid:0x130db9400860003 type:create
> cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> Path:/hbase/table Error:KeeperErrorCode = NodeExists for /hbase/table
> 2011-06-29 08:26:08,353 DEBUG
> org.apache.hadoop.hbase.catalog.CatalogTracker: Starting catalog tracker
> org.apache.hadoop.hbase.catalog.CatalogTracker@3fe2670b
> 2011-06-29 08:26:08,379 INFO org.apache.hadoop.hbase.master.HMaster: Server
> active/primary master; wittgenstein:52888, sessionid=0x130db9400860000,
> cluster-up flag was=false
> 2011-06-29 08:26:08,380 DEBUG
> org.apache.hadoop.hbase.catalog.CatalogTracker: Starting catalog tracker
> org.apache.hadoop.hbase.catalog.CatalogTracker@a68cb6b
> 2011-06-29 08:26:08,383 DEBUG
> org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
> name=MASTER_OPEN_REGION-wittgenstein:52888, corePoolSize=5, maxPoolSize=5
> 2011-06-29 08:26:08,383 DEBUG
> org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
> name=MASTER_CLOSE_REGION-wittgenstein:52888, corePoolSize=5, maxPoolSize=5
> 2011-06-29 08:26:08,383 DEBUG
> org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
> name=MASTER_SERVER_OPERATIONS-wittgenstein:52888, corePoolSize=3,
> maxPoolSize=3
> 2011-06-29 08:26:08,383 DEBUG
> org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
> name=MASTER_META_SERVER_OPERATIONS-wittgenstein:52888, corePoolSize=5,
> maxPoolSize=5
> 2011-06-29 08:26:08,384 DEBUG
> org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
> name=MASTER_TABLE_OPERATIONS-wittgenstein:52888, corePoolSize=1,
> maxPoolSize=1
> 2011-06-29 08:26:08,386 DEBUG org.apache.hadoop.hbase.master.LogCleaner: Add
> log cleaner in chain: org.apache.hadoop.hbase.master.TimeToLiveLogCleaner
> 2011-06-29 08:26:08,389 INFO
> org.apache.hadoop.hbase.regionserver.MemStoreFlusher:
> globalMemStoreLimit=396.7m, globalMemStoreLimitLowMark=347.1m,
> maxHeap=991.7m
> 2011-06-29 08:26:08,395 DEBUG
> org.apache.hadoop.hbase.regionserver.PriorityCompactionQueue: Create
> PriorityCompactionQueue
> 2011-06-29 08:26:08,396 INFO
> org.apache.hadoop.hbase.regionserver.HRegionServer: Runs every 10000000ms
> 2011-06-29 08:26:08,428 INFO
> org.apache.hadoop.hbase.regionserver.HRegionServer: Attempting connect to
> Master server at wittgenstein:52888
> 2011-06-29 08:26:08,503 INFO
> org.apache.hadoop.hbase.regionserver.HRegionServer: Master isn't available
> yet, retrying
> 2011-06-29 08:26:08,517 INFO org.mortbay.log: Logging to
> org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via
> org.mortbay.log.Slf4jLog
> 2011-06-29 08:26:08,566 INFO org.apache.hadoop.http.HttpServer: Added global
> filtersafety (class=org.apache.hadoop.http.HttpServer$QuotingInputFilter)
> 2011-06-29 08:26:08,569 INFO org.apache.hadoop.http.HttpServer: Port
> returned by webServer.getConnectors()[0].getLocalPort() before open() is -1.
> Opening the listener on 60010
> 2011-06-29 08:26:08,569 INFO org.apache.hadoop.http.HttpServer:
> listener.getLocalPort() returned 60010
> webServer.getConnectors()[0].getLocalPort() returned 60010
> 2011-06-29 08:26:08,569 INFO org.apache.hadoop.http.HttpServer: Jetty bound
> to port 60010
> 2011-06-29 08:26:08,569 INFO org.mortbay.log: jetty-6.1.26
> 2011-06-29 08:26:08,864 INFO org.mortbay.log: Started
> SelectChannelConnector@0.0.0.0:60010
> 2011-06-29 08:26:08,864 DEBUG org.apache.hadoop.hbase.master.HMaster:
> Started service threads
> 2011-06-29 08:26:10,364 INFO org.apache.hadoop.hbase.master.ServerManager:
> Waiting on regionserver(s) to checkin
> 2011-06-29 08:26:11,503 INFO
> org.apache.hadoop.hbase.regionserver.HRegionServer: Attempting connect to
> Master server at wittgenstein:52888
> 2011-06-29 08:26:11,505 INFO
> org.apache.hadoop.hbase.regionserver.HRegionServer: Connected to master at
> wittgenstein:52888
> 2011-06-29 08:26:11,550 INFO
> org.apache.hadoop.hbase.regionserver.HRegionServer: Telling master at
> wittgenstein:52888 that we are up
> 2011-06-29 08:26:11,553 INFO org.apache.hadoop.hbase.master.ServerManager:
> Registering server=localhost.localdomain,49976,1309353968214, regionCount=0,
> userLoad=false
> 2011-06-29 08:26:11,564 INFO
> org.apache.hadoop.hbase.regionserver.HRegionServer: Master passed us address
> to use. Was=wittgenstein:49976, Now=localhost.localdomain:49976
> 2011-06-29 08:26:11,564 DEBUG
> org.apache.hadoop.hbase.regionserver.HRegionServer: Config from master:
> fs.default.name=file:///
> 2011-06-29 08:26:11,564 DEBUG
> org.apache.hadoop.hbase.regionserver.HRegionServer: Config from master:
> hbase.rootdir=file:///tmp/hbase-hbase/hbase
> 2011-06-29 08:26:11,569 DEBUG
> org.apache.hadoop.hbase.regionserver.HRegionServer:
> logdir=file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,49976,1309353968214
> 2011-06-29 08:26:11,572 INFO org.apache.hadoop.hbase.regionserver.wal.HLog:
> HLog configuration: blocksize=32 MB, rollsize=30.4 MB, enabled=true,
> flushlogentries=1, optionallogflushinternal=1000ms
> 2011-06-29 08:26:11,603 WARN org.apache.hadoop.util.NativeCodeLoader: Unable
> to load native-hadoop library for your platform... using builtin-java
> classes where applicable
> 2011-06-29 08:26:11,610 INFO
> org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogWriter: syncFs --
> HDFS-200 -- not available, dfs.support.append=false
> 2011-06-29 08:26:11,611 INFO org.apache.hadoop.hbase.regionserver.wal.HLog:
> New hlog
> /tmp/hbase-hbase/hbase/.logs/localhost.localdomain,49976,1309353968214/localhost.localdomain%3A49976.1309353971573
> 2011-06-29 08:26:11,611 INFO org.apache.hadoop.hbase.regionserver.wal.HLog:
> getNumCurrentReplicas--HDFS-826 not available;
> hdfs_out=org.apache.hadoop.fs.ChecksumFileSystem$ChecksumFSOutputSummer@79ca209e,
> exception=org.apache.hadoop.fs.ChecksumFileSystem$ChecksumFSOutputSummer.getNumCurrentReplicas()
> 2011-06-29 08:26:11,612 INFO org.apache.hadoop.metrics.jvm.JvmMetrics:
> Cannot initialize JVM Metrics with processName=RegionServer,
> sessionId=RegionServer:0;wittgenstein,49976,1309353968214 - already
> initialized
> 2011-06-29 08:26:11,613 INFO org.apache.hadoop.hbase.metrics: new MBeanInfo
> 2011-06-29 08:26:11,613 INFO
> org.apache.hadoop.hbase.regionserver.metrics.RegionServerMetrics:
> Initialized
> 2011-06-29 08:26:11,614 DEBUG
> org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
> name=RS_OPEN_REGION-localhost.localdomain,49976,1309353968214,
> corePoolSize=3, maxPoolSize=3
> 2011-06-29 08:26:11,614 DEBUG
> org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
> name=RS_OPEN_ROOT-localhost.localdomain,49976,1309353968214, corePoolSize=1,
> maxPoolSize=1
> 2011-06-29 08:26:11,614 DEBUG
> org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
> name=RS_OPEN_META-localhost.localdomain,49976,1309353968214, corePoolSize=1,
> maxPoolSize=1
> 2011-06-29 08:26:11,614 DEBUG
> org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
> name=RS_CLOSE_REGION-localhost.localdomain,49976,1309353968214,
> corePoolSize=3, maxPoolSize=3
> 2011-06-29 08:26:11,614 DEBUG
> org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
> name=RS_CLOSE_ROOT-localhost.localdomain,49976,1309353968214,
> corePoolSize=1, maxPoolSize=1
> 2011-06-29 08:26:11,614 DEBUG
> org.apache.hadoop.hbase.executor.ExecutorService: Starting executor service
> name=RS_CLOSE_META-localhost.localdomain,49976,1309353968214,
> corePoolSize=1, maxPoolSize=1
> 2011-06-29 08:26:11,630 INFO org.apache.hadoop.http.HttpServer: Added global
> filtersafety (class=org.apache.hadoop.http.HttpServer$QuotingInputFilter)
> 2011-06-29 08:26:11,630 INFO org.apache.hadoop.http.HttpServer: Port
> returned by webServer.getConnectors()[0].getLocalPort() before open() is -1.
> Opening the listener on 60030
> 2011-06-29 08:26:11,630 INFO org.apache.hadoop.http.HttpServer:
> listener.getLocalPort() returned 60030
> webServer.getConnectors()[0].getLocalPort() returned 60030
> 2011-06-29 08:26:11,630 INFO org.apache.hadoop.http.HttpServer: Jetty bound
> to port 60030
> 2011-06-29 08:26:11,630 INFO org.mortbay.log: jetty-6.1.26
> 2011-06-29 08:26:11,750 INFO org.mortbay.log: Started
> SelectChannelConnector@0.0.0.0:60030
> 2011-06-29 08:26:11,757 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> Responder: starting
> 2011-06-29 08:26:11,758 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> listener on 49976: starting
> 2011-06-29 08:26:11,758 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 0 on 49976: starting
> 2011-06-29 08:26:11,758 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 1 on 49976: starting
> 2011-06-29 08:26:11,759 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 2 on 49976: starting
> 2011-06-29 08:26:11,759 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 3 on 49976: starting
> 2011-06-29 08:26:11,759 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 4 on 49976: starting
> 2011-06-29 08:26:11,759 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 5 on 49976: starting
> 2011-06-29 08:26:11,759 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 6 on 49976: starting
> 2011-06-29 08:26:11,759 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 7 on 49976: starting
> 2011-06-29 08:26:11,760 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 8 on 49976: starting
> 2011-06-29 08:26:11,760 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server
> handler 9 on 49976: starting
> 2011-06-29 08:26:11,760 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> Server handler 0 on 49976: starting
> 2011-06-29 08:26:11,760 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> Server handler 1 on 49976: starting
> 2011-06-29 08:26:11,760 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> Server handler 2 on 49976: starting
> 2011-06-29 08:26:11,761 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> Server handler 3 on 49976: starting
> 2011-06-29 08:26:11,761 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> Server handler 4 on 49976: starting
> 2011-06-29 08:26:11,761 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> Server handler 5 on 49976: starting
> 2011-06-29 08:26:11,761 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> Server handler 6 on 49976: starting
> 2011-06-29 08:26:11,761 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> Server handler 7 on 49976: starting
> 2011-06-29 08:26:11,762 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> Server handler 8 on 49976: starting
> 2011-06-29 08:26:11,762 INFO
> org.apache.hadoop.hbase.regionserver.HRegionServer: Serving as
> localhost.localdomain,49976,1309353968214, RPC listening on /127.0.1.1:49976,
> sessionid=0x130db9400860002
> 2011-06-29 08:26:11,763 INFO org.apache.hadoop.ipc.HBaseServer: PRI IPC
> Server handler 9 on 49976: starting
> 2011-06-29 08:26:11,766 INFO org.apache.hadoop.hbase.regionserver.StoreFile:
> Allocating LruBlockCache with maximum size 198.3m
> 2011-06-29 08:26:11,865 INFO org.apache.hadoop.hbase.master.ServerManager:
> Waiting on regionserver(s) count to settle; currently=1
> 2011-06-29 08:26:13,365 INFO org.apache.hadoop.hbase.master.ServerManager:
> Finished waiting for regionserver count to settle; count=1, sleptFor=4500
> 2011-06-29 08:26:13,366 INFO org.apache.hadoop.hbase.master.ServerManager:
> Exiting wait on regionserver(s) to checkin; count=1, stopped=false, count of
> regions out on cluster=0
> 2011-06-29 08:26:13,368 INFO
> org.apache.hadoop.hbase.master.MasterFileSystem: Log folder
> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,49976,1309353968214
> belongs to an existing region server
> 2011-06-29 08:26:13,368 INFO
> org.apache.hadoop.hbase.master.MasterFileSystem: Log folder
> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764
> doesn't belong to a known region server, splitting
> 2011-06-29 08:26:13,381 INFO
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Splitting 1 hlog(s)
> in
> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764
> 2011-06-29 08:26:13,382 DEBUG
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Writer thread
> Thread[WriterThread-0,5,main]: starting
> 2011-06-29 08:26:13,387 DEBUG
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Writer thread
> Thread[WriterThread-1,5,main]: starting
> 2011-06-29 08:26:13,388 DEBUG
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Writer thread
> Thread[WriterThread-2,5,main]: starting
> 2011-06-29 08:26:13,388 DEBUG
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Splitting hlog 1 of
> 1:
> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764/localhost.localdomain%3A38896.1309353525054,
> length=0
> 2011-06-29 08:26:13,389 WARN org.apache.hadoop.hbase.util.FSUtils: Running
> on HDFS without append enabled may result in data loss
> 2011-06-29 08:26:13,389 WARN
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: File
> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764/localhost.localdomain%3A38896.1309353525054
> might be still open, length is 0
> 2011-06-29 08:26:13,399 WARN org.apache.hadoop.fs.FSInputChecker: Problem
> opening checksum file:
> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764/localhost.localdomain%3A38896.1309353525054.
>  Ignoring exception: java.io.EOFException
> at java.io.DataInputStream.readFully(DataInputStream.java:180)
> at java.io.DataInputStream.readFully(DataInputStream.java:152)
> at
> org.apache.hadoop.fs.ChecksumFileSystem$ChecksumFSInputChecker.<init>(ChecksumFileSystem.java:135)
> at org.apache.hadoop.fs.ChecksumFileSystem.open(ChecksumFileSystem.java:284)
> at org.apache.hadoop.io.SequenceFile$Reader.openFile(SequenceFile.java:1452)
> at
> org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader$WALReader.openFile(SequenceFileLogReader.java:65)
> at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1431)
> at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1424)
> at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1419)
> at
> org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader$WALReader.<init>(SequenceFileLogReader.java:57)
> at
> org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader.init(SequenceFileLogReader.java:158)
> at org.apache.hadoop.hbase.regionserver.wal.HLog.getReader(HLog.java:576)
> at
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.getReader(HLogSplitter.java:477)
> at
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.parseHLog(HLogSplitter.java:414)
> at
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.splitLog(HLogSplitter.java:262)
> at
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.splitLog(HLogSplitter.java:188)
> at
> org.apache.hadoop.hbase.master.MasterFileSystem.splitLog(MasterFileSystem.java:197)
> at
> org.apache.hadoop.hbase.master.MasterFileSystem.splitLogAfterStartup(MasterFileSystem.java:181)
> at
> org.apache.hadoop.hbase.master.HMaster.finishInitialization(HMaster.java:384)
> at org.apache.hadoop.hbase.master.HMaster.run(HMaster.java:283)
> at
> org.apache.hadoop.hbase.master.HMasterCommandLine$LocalHMaster.run(HMasterCommandLine.java:193)
> at java.lang.Thread.run(Thread.java:662)
>
> 2011-06-29 08:26:13,402 WARN
> org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader: Error while
> trying to get accurate file length.  Truncation / data loss may occur if
> RegionServers die.
> java.lang.NoSuchMethodException:
> org.apache.hadoop.fs.ChecksumFileSystem$ChecksumFSInputChecker.getFileLength()
> at java.lang.Class.getMethod(Class.java:1605)
> at
> org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader$WALReader$WALReaderFSDataInputStream.getPos(SequenceFileLogReader.java:107)
> at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1436)
> at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1424)
> at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1419)
> at
> org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader$WALReader.<init>(SequenceFileLogReader.java:57)
> at
> org.apache.hadoop.hbase.regionserver.wal.SequenceFileLogReader.init(SequenceFileLogReader.java:158)
> at org.apache.hadoop.hbase.regionserver.wal.HLog.getReader(HLog.java:576)
> at
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.getReader(HLogSplitter.java:477)
> at
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.parseHLog(HLogSplitter.java:414)
> at
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.splitLog(HLogSplitter.java:262)
> at
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter.splitLog(HLogSplitter.java:188)
> at
> org.apache.hadoop.hbase.master.MasterFileSystem.splitLog(MasterFileSystem.java:197)
> at
> org.apache.hadoop.hbase.master.MasterFileSystem.splitLogAfterStartup(MasterFileSystem.java:181)
> at
> org.apache.hadoop.hbase.master.HMaster.finishInitialization(HMaster.java:384)
> at org.apache.hadoop.hbase.master.HMaster.run(HMaster.java:283)
> at
> org.apache.hadoop.hbase.master.HMasterCommandLine$LocalHMaster.run(HMasterCommandLine.java:193)
> at java.lang.Thread.run(Thread.java:662)
> 2011-06-29 08:26:13,405 WARN
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Could not open
> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764/localhost.localdomain%3A38896.1309353525054
> for reading. File is emptyjava.io.EOFException
> 2011-06-29 08:26:13,408 INFO
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Archived processed
> log
> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764/localhost.localdomain%3A38896.1309353525054
> to
> file:/tmp/hbase-hbase/hbase/.oldlogs/localhost.localdomain%3A38896.1309353525054
> 2011-06-29 08:26:13,409 INFO
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Waiting for split
> writer threads to finish
> 2011-06-29 08:26:14,388 INFO
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: Split writers
> finished
> 2011-06-29 08:26:14,389 INFO
> org.apache.hadoop.hbase.regionserver.wal.HLogSplitter: hlog file splitting
> completed in 1010 ms for
> file:/tmp/hbase-hbase/hbase/.logs/localhost.localdomain,38896,1309353521764
> 2011-06-29 08:26:15,394 INFO
> org.apache.hadoop.hbase.catalog.RootLocationEditor: Unsetting ROOT region
> location in ZooKeeper
> 2011-06-29 08:26:15,396 INFO
> org.apache.zookeeper.server.PrepRequestProcessor: Got user-level
> KeeperException when processing sessionid:0x130db9400860000 type:delete
> cxid:0x11 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
> Path:/hbase/root-region-server Error:KeeperErrorCode = NoNode for
> /hbase/root-region-server
> 2011-06-29 08:26:15,423 DEBUG org.apache.hadoop.hbase.zookeeper.ZKAssign:
> master:52888-0x130db9400860000 Creating (or updating) unassigned node for
> 70236052 with OFFLINE state
> 2011-06-29 08:26:15,440 DEBUG
> org.apache.hadoop.hbase.master.AssignmentManager: No previous transition
> plan was found (or we are ignoring an existing plan) for -ROOT-,,0.70236052
> so generated a random one; hri=-ROOT-,,0.70236052, src=,
> dest=localhost.localdomain,49976,1309353968214; 1 (online=1, exclude=null)
> available servers
> 2011-06-29 08:26:15,440 DEBUG
> org.apache.hadoop.hbase.master.AssignmentManager: Assigning region
> -ROOT-,,0.70236052 to localhost.localdomain,49976,1309353968214
> 2011-06-29 08:26:15,440 DEBUG org.apache.hadoop.hbase.master.ServerManager:
> New connection to localhost.localdomain,49976,1309353968214
> 2011-06-29 08:26:15,462 INFO org.apache.hadoop.ipc.HbaseRPC: Server at /
> 127.0.0.1:49976 could not be reached after 1 tries, giving up.
> 2011-06-29 08:26:15,463 WARN
> org.apache.hadoop.hbase.master.AssignmentManager: Failed assignment of
> -ROOT-,,0.70236052 to serverName=localhost.localdomain,49976,1309353968214,
> load=(requests=0, regions=0, usedHeap=31, maxHeap=991), trying to assign
> elsewhere instead; retry=0
> org.apache.hadoop.hbase.client.RetriesExhaustedException: Failed setting up
> proxy interface org.apache.hadoop.hbase.ipc.HRegionInterface to /
> 127.0.0.1:49976 after attempts=1
> at org.apache.hadoop.hbase.ipc.HBaseRPC.waitForProxy(HBaseRPC.java:355)
> at
> org.apache.hadoop.hbase.client.HConnectionManager$HConnectionImplementation.getHRegionConnection(HConnectionManager.java:957)
> at
> org.apache.hadoop.hbase.master.ServerManager.getServerConnection(ServerManager.java:606)
> at
> org.apache.hadoop.hbase.master.ServerManager.sendRegionOpen(ServerManager.java:541)
> at
> org.apache.hadoop.hbase.master.AssignmentManager.assign(AssignmentManager.java:901)
> at
> org.apache.hadoop.hbase.master.AssignmentManager.assign(AssignmentManager.java:730)
> at
> org.apache.hadoop.hbase.master.AssignmentManager.assign(AssignmentManager.java:710)
> at
> org.apache.hadoop.hbase.master.AssignmentManager.assignRoot(AssignmentManager.java:1170)
> at
> org.apache.hadoop.hbase.master.HMaster.assignRootAndMeta(HMaster.java:431)
> at
> org.apache.hadoop.hbase.master.HMaster.finishInitialization(HMaster.java:388)
> at org.apache.hadoop.hbase.master.HMaster.run(HMaster.java:283)
> at
> org.apache.hadoop.hbase.master.HMasterCommandLine$LocalHMaster.run(HMasterCommandLine.java:193)
> at java.lang.Thread.run(Thread.java:662)
> Caused by: java.net.ConnectException: Connection refused
> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:567)
> at
> org.apache.hadoop.net.SocketIOWithTimeout.connect(SocketIOWithTimeout.java:206)
> at org.apache.hadoop.net.NetUtils.connect(NetUtils.java:408)
> at
> org.apache.hadoop.hbase.ipc.HBaseClient$Connection.setupIOstreams(HBaseClient.java:328)
> at
> org.apache.hadoop.hbase.ipc.HBaseClient.getConnection(HBaseClient.java:883)
> at org.apache.hadoop.hbase.ipc.HBaseClient.call(HBaseClient.java:750)
> at org.apache.hadoop.hbase.ipc.HBaseRPC$Invoker.invoke(HBaseRPC.java:257)
> at $Proxy7.getProtocolVersion(Unknown Source)
> at org.apache.hadoop.hbase.ipc.HBaseRPC.getProxy(HBaseRPC.java:419)
> at org.apache.hadoop.hbase.ipc.HBaseRPC.getProxy(HBaseRPC.java:393)
> at org.apache.hadoop.hbase.ipc.HBaseRPC.getProxy(HBaseRPC.java:444)
> at org.apache.hadoop.hbase.ipc.HBaseRPC.waitForProxy(HBaseRPC.java:349)
> ... 12 more
> 2011-06-29 08:26:15,463 WARN
> org.apache.hadoop.hbase.master.AssignmentManager: Unable to find a viable
> location to assign region -ROOT-,,0.70236052
>
> 127.0.0.1 localhost.localdomain localhost
> ::1 wittgenstein localhost6.localdomain6 localhost6
> 127.0.1.1 wittgenstein
>
> # The following lines are desirable for IPv6 capable hosts
> ::1     localhost ip6-localhost ip6-loopback
> fe00::0 ip6-localnet
> ff00::0 ip6-mcastprefix
> ff02::1 ip6-allnodes
> ff02::2 ip6-allrouters
> ff02::3 ip6-allhosts
>