[
https://issues.apache.org/jira/browse/HBASE-16212?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15372133#comment-15372133
]
Zhihua Deng edited comment on HBASE-16212 at 7/12/16 3:44 AM:
--------------------------------------------------------------
Some related logs from region server:
2016-07-10 21:31:42,147 INFO
[B.defaultRpcServer.handler=22,queue=1,port=16020] hdfs.DFSClient:
DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
111506876, targetPos: 111506843
2016-07-10 21:31:42,715 INFO
[B.defaultRpcServer.handler=25,queue=1,port=16020] hdfs.DFSClient:
DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
113544644, targetPos: 113544611
2016-07-10 21:31:43,341 INFO
[B.defaultRpcServer.handler=27,queue=0,port=16020] hdfs.DFSClient:
DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
115547269, targetPos: 115547236
2016-07-10 21:31:43,950 INFO
[B.defaultRpcServer.handler=16,queue=1,port=16020] hdfs.DFSClient:
DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
117532235, targetPos: 117532202
2016-07-10 21:31:43,988 INFO [B.defaultRpcServer.handler=7,queue=1,port=16020]
hdfs.DFSClient: DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
119567867, targetPos: 119567834
2016-07-10 21:31:45,228 INFO
[B.defaultRpcServer.handler=19,queue=1,port=16020] hdfs.DFSClient:
DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
121787264, targetPos: 121787231
2016-07-10 21:31:45,254 INFO [B.defaultRpcServer.handler=0,queue=0,port=16020]
hdfs.DFSClient: DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
123910032, targetPos: 123909999
2016-07-10 21:31:46,402 INFO
[B.defaultRpcServer.handler=26,queue=2,port=16020] hdfs.DFSClient:
DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
125905163, targetPos: 125905130
2016-07-10 21:31:47,027 INFO
[B.defaultRpcServer.handler=24,queue=0,port=16020] hdfs.DFSClient:
DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
128028947, targetPos: 128028914
2016-07-10 21:31:47,649 INFO
[B.defaultRpcServer.handler=10,queue=1,port=16020] hdfs.DFSClient:
DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
130057763, targetPos: 130057730
was (Author: dengzh):
Some related logs from region server:
<pre>
2016-07-10 21:31:42,147 INFO
[B.defaultRpcServer.handler=22,queue=1,port=16020] hdfs.DFSClient:
DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
111506876, targetPos: 111506843
2016-07-10 21:31:42,715 INFO
[B.defaultRpcServer.handler=25,queue=1,port=16020] hdfs.DFSClient:
DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
113544644, targetPos: 113544611
2016-07-10 21:31:43,341 INFO
[B.defaultRpcServer.handler=27,queue=0,port=16020] hdfs.DFSClient:
DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
115547269, targetPos: 115547236
2016-07-10 21:31:43,950 INFO
[B.defaultRpcServer.handler=16,queue=1,port=16020] hdfs.DFSClient:
DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
117532235, targetPos: 117532202
2016-07-10 21:31:43,988 INFO [B.defaultRpcServer.handler=7,queue=1,port=16020]
hdfs.DFSClient: DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
119567867, targetPos: 119567834
2016-07-10 21:31:45,228 INFO
[B.defaultRpcServer.handler=19,queue=1,port=16020] hdfs.DFSClient:
DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
121787264, targetPos: 121787231
2016-07-10 21:31:45,254 INFO [B.defaultRpcServer.handler=0,queue=0,port=16020]
hdfs.DFSClient: DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
123910032, targetPos: 123909999
2016-07-10 21:31:46,402 INFO
[B.defaultRpcServer.handler=26,queue=2,port=16020] hdfs.DFSClient:
DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
125905163, targetPos: 125905130
2016-07-10 21:31:47,027 INFO
[B.defaultRpcServer.handler=24,queue=0,port=16020] hdfs.DFSClient:
DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
128028947, targetPos: 128028914
2016-07-10 21:31:47,649 INFO
[B.defaultRpcServer.handler=10,queue=1,port=16020] hdfs.DFSClient:
DFSClient_NONMAPREDUCE_1984924661_1 seek
DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
130057763, targetPos: 130057730
</pre>
> Many Connections are created by wrong seeking pos on InputStream
> ----------------------------------------------------------------
>
> Key: HBASE-16212
> URL: https://issues.apache.org/jira/browse/HBASE-16212
> Project: HBase
> Issue Type: Bug
> Affects Versions: 1.1.2
> Reporter: Zhihua Deng
>
> As described in https://issues.apache.org/jira/browse/HDFS-8659, the datanode
> is suffering from logging the same repeatedly. Adding log to DFSInputStream,
> it outputs as follows:
> 2016-07-10 21:31:42,147 INFO
> [B.defaultRpcServer.handler=22,queue=1,port=16020] hdfs.DFSClient:
> DFSClient_NONMAPREDUCE_1984924661_1 seek
> DatanodeInfoWithStorage[10.130.1.29:50010,DS-086bc494-d862-470c-86e8-9cb7929985c6,DISK]
> for BP-360285305-10.130.1.11-1444619256876:blk_1109360829_35627143. pos:
> 111506876, targetPos: 111506843
> ...
> As the pos of this input stream is larger than targetPos(the pos trying to
> seek), A new connection to the datanode will be created, the older one will
> be closed as a consequence. When the wrong seeking ops are large, the
> datanode's block scanner info message is spamming logs, as well as many
> connections to the same datanode will be created.
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
