我的云之旅–HBase调试(139)

现在的位置: 首页 > 综合 > 正文

我的云之旅–HBase调试(139)

2013年08月21日 ⁄ 综合 ⁄ 共 14012字 ⁄ 字号小中大 ⁄ 评论关闭

下面是运行日志：

<?xml version="1.0"?>HBase-site.xml

<?xml-stylesheet type="text/xsl" href="configuration.xsl"?>

See
the NOTICE file * distributed with this work for additional information * regarding copyright ownership. The ASF licenses this file * to you under the Apache

License,
Version 2.0 (the * "License"); you may not use this file except in compliance * with the License. You may obtain a copy of the License at * * http://www.apache.org/licenses/LICENSE-2.0

*
* Unless required by applicable law or agreed to in writing, software * distributed under the License is distributed on an "AS IS" BASIS, * WITHOUT WARRANTIES

OR
CONDITIONS OF ANY KIND, either express or implied. * See the License for the specific language governing permissions and * limitations under the License. */ -->

<name>hbase.rootdir</name>

<value>file:///D:/hbase-0.20.3/data</value>

<description>The
directory shared by region servers.

Should
be fully-qualified to include the filesystem to use.

E.g:
hdfs://NAMENODE_SERVER:PORT/HBASE_ROOTDIR

</description>

</property>

<name>hbase.cluster.distributed</name>

<value>false
</value>

<description>The
mode the cluster will be in. Possible values are

false:
standalone and pseudo-distributed setups with managed Zookeeper

true:
fully-distributed

with
unmanaged Zookeeper Quorum (see hbase-env.sh)

</description>

</property>

<name>hbase.tmp.dir</name>

<value>D:/hbase-0.20.3/tmp
</value>

<description>Temporary
directory on the local filesystem.</description>

</property>

</configuration>

1 ZooKeeper日志：

2012-04-13 22:16:30,734 - INFO [main:QuorumPeerConfig@90] - Reading configuration from: G:\program\zookeeper-3.3.3\conf\zoo.cfg

2012-04-13 22:16:30,750 - WARN [main:QuorumPeerMain@105] - Either no config or no quorum defined in config, running in standalone mode

2012-04-13 22:16:30,843 - INFO [main:QuorumPeerConfig@90] - Reading configuration from: G:\program\zookeeper-3.3.3\conf\zoo.cfg

2012-04-13 22:16:30,843 - INFO [main:ZooKeeperServerMain@94] - Starting server

2012-04-13 22:16:30,890 - INFO [main:Environment@97] - Server environment:zookeeper.version=3.3.3-1073969, built on 02/24/2011 03:33 GMT

2012-04-13 22:16:30,890 - INFO [main:Environment@97] - Server environment:host.name=rollin

2012-04-13 22:16:30,890 - INFO [main:Environment@97] - Server environment:java.version=1.6.0_22

2012-04-13 22:16:30,890 - INFO [main:Environment@97] - Server environment:java.vendor=Sun Microsystems Inc.

2012-04-13 22:16:30,890 - INFO [main:Environment@97] - Server environment:java.home=C:\glassfishv3\jdk\jre

2012-04-13 22:16:30,890 - INFO [main:Environment@97] - Server environment:java.class.path=G:\program\zookeeper-3.3.3\bin;G:\program\zookeeper-3.3.3\contrib\bookkeeper\zookeeper-3.3.3-bookkeeper.jar;G:\program\zookeeper-3.3.3\contrib\fatjar\zookeeper-3.3.3-fatjar.jar;G:\program\zookeeper-3.3.3\contrib\rest\zookeeper-3.3.3-rest.jar;G:\program\zookeeper-3.3.3\contrib\ZooInspector\lib\jtoaster-1.0.4.jar;G:\program\zookeeper-3.3.3\contrib\ZooInspector\lib;G:\program\zookeeper-3.3.3\contrib\ZooInspector\zookeeper-3.3.3-ZooInspector.jar;G:\program\zookeeper-3.3.3\dist-maven\bookkeeper-3.3.3.jar;G:\program\zookeeper-3.3.3\dist-maven\zookeeper-3.3.3-javadoc.jar;G:\program\zookeeper-3.3.3\dist-maven\zookeeper-3.3.3-sources.jar;G:\program\zookeeper-3.3.3\dist-maven\zookeeper-3.3.3.jar;G:\program\zookeeper-3.3.3\lib\jline-0.9.94.jar;G:\program\zookeeper-3.3.3\lib\log4j-1.2.15.jar;G:\program\zookeeper-3.3.3\src\contrib\zooinspector\lib\jtoaster-1.0.4.jar;G:\program\zookeeper-3.3.3\zookeeper-3.3.3.jar

2012-04-13 22:16:30,906 - INFO [main:Environment@97] - Server environment:java.library.path=C:\glassfishv3\jdk\bin;.;C:\WINDOWS\Sun\Java\bin;C:\WINDOWS\system32;C:\WINDOWS;C:/glassfishv3/jdk/bin/../jre/bin/client;C:/glassfishv3/jdk/bin/../jre/bin;C:/glassfishv3/jdk/bin/../jre/lib/i386;C:\WINDOWS\System32;C:\glassfishv3\jdk\bin;C:\Program
Files\Common Files\Thunder Network\KanKan\Codecs;C:\Program Files\MySQL\MySQL Server 5.0\bin;C:\Program Files\Microsoft SQL Server\80\Tools\Binn\;C:\glassfishv3\jdk\bin;C:\Program Files\Common Files\Thunder Network\KanKan\Codecs;C:\Program Files\MySQL\MySQL
Server 5.0\bin;C:\Program Files\Microsoft SQL Server\80\Tools\Binn\;C:\glassfishv3\jdk\bin;C:\Program Files\Common Files\Thunder Network\KanKan\Codecs;C:\Program Files\MySQL\MySQL Server 5.0\bin;C:\Program Files\Microsoft SQL Server\80\Tools\Binn\;C:\WINDOWS\System32;C:\glassfishv3\jdk\bin;C:\Program
Files\Common Files\Thunder Network\KanKan\Codecs;C:\Program Files\MySQL\MySQL Server 5.0\bin;C:\Program Files\Microsoft SQL Server\80\Tools\Binn\;C:\glassfishv3\jdk\bin;C:\Program Files\Common Files\Thunder Network\KanKan\Codecs;C:\Program Files\MySQL\MySQL
Server 5.0\bin;C:\Program Files\Microsoft SQL Server\80\Tools\Binn\;C:\glassfishv3\jdk\bin;C:\Program Files\Common Files\Thunder Network\KanKan\Codecs;C:\Program Files\MySQL\MySQL Server 5.0\bin;C:\Program Files\Microsoft SQL Server\80\Tools\Binn\;G:\JavaEE\apache-maven-3.0.3\bin;C:\Program
Files\Subversion\bin;G:\JavaEE\apache2.2\bin;G:\cygwin\bin;G:\cygwin\usr\bin;C:\glassfishv3\jdk\bin;G:\JavaEE\eclipse;

2012-04-13 22:16:30,906 - INFO [main:Environment@97] - Server environment:java.io.tmpdir=C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\

2012-04-13 22:16:30,906 - INFO [main:Environment@97] - Server environment:java.compiler=<NA>

2012-04-13 22:16:30,906 - INFO [main:Environment@97] - Server environment:os.name=Windows XP

2012-04-13 22:16:30,906 - INFO [main:Environment@97] - Server environment:os.arch=x86

2012-04-13 22:16:30,906 - INFO [main:Environment@97] - Server environment:os.version=5.1

2012-04-13 22:16:30,906 - INFO [main:Environment@97] - Server environment:user.name=Administrator

2012-04-13 22:16:30,906 - INFO [main:Environment@97] - Server environment:user.home=C:\Documents and Settings\Administrator

2012-04-13 22:16:30,906 - INFO [main:Environment@97] - Server environment:user.dir=G:\program\zookeeper-3.3.3

2012-04-13 22:16:31,046 - INFO [main:ZooKeeperServer@663] - tickTime set to 2000

2012-04-13 22:16:31,046 - INFO [main:ZooKeeperServer@672] - minSessionTimeout set to -1

2012-04-13 22:16:31,046 - INFO [main:ZooKeeperServer@681] - maxSessionTimeout set to -1

2012-04-13 22:16:31,140 - INFO [main:NIOServerCnxn$Factory@143] - binding to port 0.0.0.0/0.0.0.0:2181

2012-04-13 22:16:31,187 - INFO [main:FileTxnSnapLog@208] - Snapshotting: 0

2012-04-13 22:19:36,359 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn$Factory@251] - Accepted socket connection from /127.0.0.1:2064

2012-04-13 22:19:36,390 - WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@639] - Exception causing close of session 0x0 due to java.io.IOException: Len error 1195725856

2012-04-13 22:19:36,390 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1435] - Closed socket connection for client /127.0.0.1:2064 (no session established for client)

2012-04-13 22:24:10,171 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn$Factory@251] - Accepted socket connection from /127.0.0.1:2121

2012-04-13 22:24:10,203 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@777] - Client attempting to establish new session at /127.0.0.1:2121

2012-04-13 22:24:10,203 - INFO [SyncThread:0:FileTxnLog@197] - Creating new log file: log.1

2012-04-13 22:24:12,125 - INFO [SyncThread:0:NIOServerCnxn@1580] - Established session 0x136ac0ffee30000 with negotiated timeout 40000 for client /127.0.0.1:2121

2012-04-13 22:24:12,375 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn$Factory@251] - Accepted socket connection from /127.0.0.1:2129

2012-04-13 22:24:12,375 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@777] - Client attempting to establish new session at /127.0.0.1:2129

2012-04-13 22:24:12,406 - INFO [SyncThread:0:NIOServerCnxn@1580] - Established session 0x136ac0ffee30001 with negotiated timeout 40000 for client /127.0.0.1:2129

2012-04-13 22:24:12,546 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn$Factory@251] - Accepted socket connection from /127.0.0.1:2132

2012-04-13 22:24:12,546 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@777] - Client attempting to establish new session at /127.0.0.1:2132

2012-04-13 22:24:12,625 - INFO [SyncThread:0:NIOServerCnxn@1580] - Established session 0x136ac0ffee30002 with negotiated timeout 40000 for client /127.0.0.1:2132

2012-04-13 22:24:36,562 - INFO [ProcessThread:-1:PrepRequestProcessor@387] - Processed session termination for sessionid: 0x136ac0ffee30000

2012-04-13 22:24:36,593 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1435] - Closed socket connection for client /127.0.0.1:2121 which had sessionid 0x136ac0ffee30000

2012-04-13 22:24:37,453 - INFO [ProcessThread:-1:PrepRequestProcessor@387] - Processed session termination for sessionid: 0x136ac0ffee30001

2012-04-13 22:24:37,500 - WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@634] - EndOfStreamException: Unable to read additional data from client sessionid 0x136ac0ffee30001, likely client has closed socket

2012-04-13 22:24:37,500 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1435] - Closed socket connection for client /127.0.0.1:2129 which had sessionid 0x136ac0ffee30001

2012-04-13 22:25:10,218 - INFO [ProcessThread:-1:PrepRequestProcessor@387] - Processed session termination for sessionid: 0x136ac0ffee30002

2012-04-13 22:25:10,281 - WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@634] - EndOfStreamException: Unable to read additional data from client sessionid 0x136ac0ffee30002, likely client has closed socket

2012-04-13 22:25:10,281 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1435] - Closed socket connection for client /127.0.0.1:2132 which had sessionid 0x136ac0ffee30002

2012-04-13 22:27:07,406 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn$Factory@251] - Accepted socket connection from /127.0.0.1:2180

2012-04-13 22:27:07,421 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@777] - Client attempting to establish new session at /127.0.0.1:2180

2012-04-13 22:27:07,484 - INFO [SyncThread:0:NIOServerCnxn@1580] - Established session 0x136ac0ffee30003 with negotiated timeout 40000 for client /127.0.0.1:2180

2012-04-13 22:27:59,203 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn$Factory@251] - Accepted socket connection from /127.0.0.1:2192

2012-04-13 22:27:59,218 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@777] - Client attempting to establish new session at /127.0.0.1:2192

2012-04-13 22:27:59,281 - INFO [SyncThread:0:NIOServerCnxn@1580] - Established session 0x136ac0ffee30004 with negotiated timeout 40000 for client /127.0.0.1:2192

2012-04-13 22:28:01,375 - INFO [ProcessThread:-1:PrepRequestProcessor@407] - Got user-level KeeperException when processing sessionid:0x136ac0ffee30003 type:create cxid:0xd zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/hbase/safe-mode Error:KeeperErrorCode
= NodeExists for /hbase/safe-mode

2 HMaster

2012-04-13 22:27:06,421 INFO [main] master.HMaster(1127): vmName=Java HotSpot(TM) Client VM, vmVendor=Sun Microsystems Inc., vmVersion=17.1-b03

2012-04-13 22:27:06,421 INFO [main] master.HMaster(1128): vmInputArguments=[-Dfile.encoding=UTF-8]

2012-04-13 22:27:06,546 INFO [main] master.HMaster(166): My address is rollin:60000

2012-04-13 22:27:07,265 INFO [main] ipc.HBaseRpcMetrics(52): Initializing RPC Metrics with hostName=HMaster, port=60000

2012-04-13 22:27:07,453 INFO [main] master.RegionManager(160): -ROOT- region unset (but not set to be reassigned)

2012-04-13 22:27:07,453 INFO [main] master.RegionManager(172): ROOT inserted into regionsInTransition

2012-04-13 22:27:07,500 DEBUG [main-EventThread] master.HMaster(1076): Got event None with path null

2012-04-13 22:27:07,515 DEBUG [main] zookeeper.ZooKeeperWrapper(382): Failed to read: org.apache.zookeeper.KeeperException$NoNodeException: KeeperErrorCode = NoNode for /hbase/master

2012-04-13 22:27:07,546 DEBUG [main] zookeeper.ZooKeeperWrapper(528): Wrote master address 192.168.1.110:60000 to ZooKeeper

2012-04-13 22:27:07,578 DEBUG [main] zookeeper.ZooKeeperWrapper(339): State node wrote in ZooKeeper

2012-04-13 22:27:07,578 DEBUG [main] zookeeper.ZooKeeperWrapper(398): Read ZNode /hbase/master got 192.168.1.110:60000

2012-04-13 22:27:07,578 INFO [main] master.HMaster(219): HMaster initialized on 192.168.1.110:60000

2012-04-13 22:27:07,578 DEBUG [HMaster] master.HMaster(514): Checking cluster state...

2012-04-13 22:27:07,593 DEBUG [HMaster] zookeeper.ZooKeeperWrapper(398): Read ZNode /hbase/root-region-server got 192.168.1.110:2122

2012-04-13 22:27:07,593 DEBUG [HMaster] master.HMaster(519): This is a fresh start, proceeding with normal startup

2012-04-13 22:27:07,593 DEBUG [HMaster] master.HMaster(568): No log files to split, proceeding...

2012-04-13 22:27:07,609 INFO [HMaster] metrics.MasterMetrics(61): Initialized

2012-04-13 22:27:07.687::INFO: Logging to STDERR via org.mortbay.log.StdErrLog

2012-04-13 22:27:07.796::INFO: jetty-6.1.14

2012-04-13 22:27:07.875::INFO: Extract jar:file:/G:/hbase/hbase-0.20.5/hbase-0.20.5.jar!/webapps/master to C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\Jetty_0_0_0_0_60010_master____q3nwom\webapp

2012-04-13 22:27:08.375::INFO: Extract jar:file:/G:/hbase/hbase-0.20.5/hbase-0.20.5.jar!/webapps/rest to C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\Jetty_0_0_0_0_60010_rest__api__uxgjk0\webapp

2012-04-13 22:27:08.671::INFO: Started SelectChannelConnector@0.0.0.0:60010

2012-04-13 22:27:08,687 DEBUG [HMaster] master.HMaster(625): Started service threads

2012-04-13 22:27:59,687 INFO [IPC Server handler 1 on 60000] master.ServerManager(166): Received start message from: rollin,60020,1334327279640

2012-04-13 22:27:59,718 DEBUG [IPC Server handler 1 on 60000] zookeeper.ZooKeeperWrapper(613): Updated ZNode /hbase/rs/1334327279640 with data 192.168.1.110:60020

2012-04-13 22:28:01,265 INFO [IPC Server handler 2 on 60000] master.RegionManager(327): Assigning region -ROOT-,,0 to rollin,60020,1334327279640

2012-04-13 22:28:01,359 INFO [IPC Server handler 3 on 60000] master.ServerManager(460): Processing MSG_REPORT_OPEN: -ROOT-,,0 from rollin,60020,1334327279640; 1 of 1

2012-04-13 22:28:01,437 WARN [IPC Server handler 3 on 60000] zookeeper.ZooKeeperWrapper(569): Failed to create out of safe mode in ZooKeeper: org.apache.zookeeper.KeeperException$NodeExistsException: KeeperErrorCode = NodeExists for /hbase/safe-mode

2012-04-13 22:28:01,437 INFO [IPC Server handler 3 on 60000] zookeeper.ZooKeeperWrapper(571): Node exists; just move on

2012-04-13 22:28:01,437 INFO [IPC Server handler 3 on 60000] master.RegionManager(1180): exiting safe mode

2012-04-13 22:28:01,453 DEBUG [IPC Server handler 3 on 60000] zookeeper.ZooKeeperWrapper(479): SetData of ZNode /hbase/root-region-server with 192.168.1.110:60020

2012-04-13 22:28:01,468 INFO [RegionManager.rootScanner] master.BaseScanner(163): RegionManager.rootScanner