From dev-return-54006-archive-asf-public=cust-asf.ponee.io@phoenix.apache.org Fri Sep 21 13:20:05 2018 Return-Path: X-Original-To: archive-asf-public@cust-asf.ponee.io Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by mx-eu-01.ponee.io (Postfix) with SMTP id 6223B180656 for ; Fri, 21 Sep 2018 13:20:04 +0200 (CEST) Received: (qmail 16482 invoked by uid 500); 21 Sep 2018 11:20:03 -0000 Mailing-List: contact dev-help@phoenix.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@phoenix.apache.org Delivered-To: mailing list dev@phoenix.apache.org Received: (qmail 16415 invoked by uid 99); 21 Sep 2018 11:20:03 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd1-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 21 Sep 2018 11:20:03 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd1-us-west.apache.org (ASF Mail Server at spamd1-us-west.apache.org) with ESMTP id 0459DC213B for ; Fri, 21 Sep 2018 11:20:03 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd1-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: -109.501 X-Spam-Level: X-Spam-Status: No, score=-109.501 tagged_above=-999 required=6.31 tests=[ENV_AND_HDR_SPF_MATCH=-0.5, KAM_ASCII_DIVIDERS=0.8, RCVD_IN_DNSWL_MED=-2.3, SPF_PASS=-0.001, USER_IN_DEF_SPF_WL=-7.5, USER_IN_WHITELIST=-100] autolearn=disabled Received: from mx1-lw-eu.apache.org ([10.40.0.8]) by localhost (spamd1-us-west.apache.org [10.40.0.7]) (amavisd-new, port 10024) with ESMTP id 836QvkFrFgMF for ; Fri, 21 Sep 2018 11:20:02 +0000 (UTC) Received: from mailrelay1-us-west.apache.org (mailrelay1-us-west.apache.org [209.188.14.139]) by mx1-lw-eu.apache.org (ASF Mail Server at mx1-lw-eu.apache.org) with ESMTP id 272005F544 for ; Fri, 21 Sep 2018 11:20:01 +0000 (UTC) Received: from jira-lw-us.apache.org (unknown [207.244.88.139]) by mailrelay1-us-west.apache.org (ASF Mail Server at mailrelay1-us-west.apache.org) with ESMTP id 594AFE006D for ; Fri, 21 Sep 2018 11:20:00 +0000 (UTC) Received: from jira-lw-us.apache.org (localhost [127.0.0.1]) by jira-lw-us.apache.org (ASF Mail Server at jira-lw-us.apache.org) with ESMTP id 1475723F99 for ; Fri, 21 Sep 2018 11:20:00 +0000 (UTC) Date: Fri, 21 Sep 2018 11:20:00 +0000 (UTC) From: "Jaanai (JIRA)" To: dev@phoenix.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Updated] (PHOENIX-4915) The client gets stuck when using same rows concurrently writing data table MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 7bit X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 [ https://issues.apache.org/jira/browse/PHOENIX-4915?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ] Jaanai updated PHOENIX-4915: ---------------------------- Attachment: test.sql test.java > The client gets stuck when using same rows concurrently writing data table > -------------------------------------------------------------------------- > > Key: PHOENIX-4915 > URL: https://issues.apache.org/jira/browse/PHOENIX-4915 > Project: Phoenix > Issue Type: Bug > Affects Versions: 4.12.0, 4.14.0 > Reporter: Jaanai > Assignee: Jaanai > Priority: Blocker > Attachments: test.java, test.sql > > > The client has got stuck when using the multi-thread writes the same rows data into a data table which has a global index. > I find that rows lock of the data table will not be released under highly writing load and throwing " ERROR 2008 (INT10): ERROR 2008 (INT10): Unable to find cached index metadata." exception information. Most of the threads will be waiting for getting the row lock in Jstack information. > The following are exceptions on the server side: > {code:java} > [B.defaultRpcServer.handler=37,queue=1,port=16020] regionserver.RSRpcServices(103): Failed doing multi operation, current call is : callId: 3455 service: ClientService meth > odName: Multi size: 23.1 K connection: 192.168.199.7:52050 param: actionCount=44#regionCount=8#LOCK,\x02,1537434393195.ee6d441a04ee6a59b24262f22f618d88.##### > org.apache.hadoop.hbase.DoNotRetryIOException: ERROR 2008 (INT10): ERROR 2008 (INT10): Unable to find cached index metadata. key=-727998515684050837 region=LOCK,\x0E,1537434393195.f4de29d4b36775589a49f > 1c7a20c73a2.host=hb-bp1v2q830426r6763-004.hbase.rds.aliyuncs.com,16020,1537434304031 Index update failed > at org.apache.phoenix.util.ServerUtil.createIOException(ServerUtil.java:88) > at org.apache.phoenix.util.ServerUtil.throwIOException(ServerUtil.java:62) > at org.apache.phoenix.index.PhoenixIndexMetaData.getIndexMetaData(PhoenixIndexMetaData.java:87) > at org.apache.phoenix.index.PhoenixIndexMetaData.(PhoenixIndexMetaData.java:103) > at org.apache.phoenix.index.PhoenixIndexBuilder.getIndexMetaData(PhoenixIndexBuilder.java:95) > at org.apache.phoenix.hbase.index.builder.IndexBuildManager.getIndexUpdate(IndexBuildManager.java:80) > at org.apache.phoenix.hbase.index.Indexer.preBatchMutateWithExceptions(Indexer.java:528) > at org.apache.phoenix.hbase.index.Indexer.preBatchMutate(Indexer.java:374) > at org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost$37.call(RegionCoprocessorHost.java:1032) > at org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost$RegionOperation.call(RegionCoprocessorHost.java:1714) > at org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost.execOperation(RegionCoprocessorHost.java:1789) > at org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost.execOperation(RegionCoprocessorHost.java:1746) > at org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost.preBatchMutate(RegionCoprocessorHost.java:1028) > at org.apache.hadoop.hbase.regionserver.HRegion.asyncBatchMutate(HRegion.java:3236) > at org.apache.hadoop.hbase.regionserver.RSRpcServices.doAsyncBatchOp(RSRpcServices.java:2147) > at org.apache.hadoop.hbase.regionserver.RSRpcServices.doNonAtomicBatchMutationCrossRegions(RSRpcServices.java:2308) > at org.apache.hadoop.hbase.regionserver.RSRpcServices.multi(RSRpcServices.java:2578) > at org.apache.hadoop.hbase.protobuf.generated.ClientProtos$ClientService$2.callBlockingMethod(ClientProtos.java:32303) > at org.apache.hadoop.hbase.ipc.RpcServer.call(RpcServer.java:2394) > at org.apache.hadoop.hbase.ipc.CallRunner.run(CallRunner.java:174) > at org.apache.hadoop.hbase.ipc.RpcExecutor$CallHandler.run(RpcExecutor.java:178) > Caused by: java.sql.SQLException: ERROR 2008 (INT10): Unable to find cached index metadata. key=-727998515684050837 region=LOCK,\x0E,1537434393195.f4de29d4b36775589a49f1c7a20c73a2.host=hb-bp1v2q830426r > 6763-004.hbase.rds.aliyuncs.com,16020,1537434304031 > at org.apache.phoenix.exception.SQLExceptionCode$Factory$1.newException(SQLExceptionCode.java:493) > at org.apache.phoenix.exception.SQLExceptionInfo.buildException(SQLExceptionInfo.java:150) > at org.apache.phoenix.index.PhoenixIndexMetaData.getIndexMetaData(PhoenixIndexMetaData.java:85) > 2018-09-20 17:35:39,254 INFO [B.defaultRpcServer.handler=13,queue=1,port=16020] regionserver.RSRpcServices(103): Failed doing multi operation, current call is : callId: 3848 service: ClientService meth > odName: Multi size: 27.2 K connection: 192.168.199.7:52042 param: actionCount=52#regionCount=8#LOCK,\x02,1537434393195.ee6d441a04ee6a59b24262f22f618d88.##### > org.apache.hadoop.hbase.exceptions.TimeoutIOException: Timed out waiting for lock for row: 0e 30 64 32 65 34 35 63 37 2d 63 63 33 64 2d 34 36 61 35 2d 61 34 38 64 2d 31 38 61 62 36 31 61 31 30 63 30 39 > at org.apache.phoenix.hbase.index.LockManager.lockRow(LockManager.java:96) > at org.apache.phoenix.hbase.index.Indexer.preBatchMutateWithExceptions(Indexer.java:425) > at org.apache.phoenix.hbase.index.Indexer.preBatchMutate(Indexer.java:374) > at org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost$37.call(RegionCoprocessorHost.java:1032) > at org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost$RegionOperation.call(RegionCoprocessorHost.java:1714) > at org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost.execOperation(RegionCoprocessorHost.java:1789) > at org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost.execOperation(RegionCoprocessorHost.java:1746) > at org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost.preBatchMutate(RegionCoprocessorHost.java:1028) > at org.apache.hadoop.hbase.regionserver.HRegion.asyncBatchMutate(HRegion.java:3236) > at org.apache.hadoop.hbase.regionserver.RSRpcServices.doAsyncBatchOp(RSRpcServices.java:2147) > at org.apache.hadoop.hbase.regionserver.RSRpcServices.doNonAtomicBatchMutationCrossRegions(RSRpcServices.java:2308) > at org.apache.hadoop.hbase.regionserver.RSRpcServices.multi(RSRpcServices.java:2578) > at org.apache.hadoop.hbase.protobuf.generated.ClientProtos$ClientService$2.callBlockingMethod(ClientProtos.java:32303) > at org.apache.hadoop.hbase.ipc.RpcServer.call(RpcServer.java:2394) > at org.apache.hadoop.hbase.ipc.CallRunner.run(CallRunner.java:174) > at org.apache.hadoop.hbase.ipc.RpcExecutor$CallHandler.run(RpcExecutor.java:178) > {code} > {code:java} > [B.defaultRpcServer.handler=37,queue=1,port=16020] regionserver.RSRpcServices(103): Failed doing multi operation, current call is : callId: 3455 service: ClientService meth > odName: Multi size: 23.1 K connection: 192.168.199.7:52050 param: actionCount=44#regionCount=8#LOCK,\x02,1537434393195.ee6d441a04ee6a59b24262f22f618d88.##### > org.apache.hadoop.hbase.DoNotRetryIOException: ERROR 2008 (INT10): ERROR 2008 (INT10): Unable to find cached index metadata. key=-727998515684050837 region=LOCK,\x0E,1537434393195.f4de29d4b36775589a49f > 1c7a20c73a2.host=hb-bp1v2q830426r6763-004.hbase.rds.aliyuncs.com,16020,1537434304031 Index update failed > at org.apache.phoenix.util.ServerUtil.createIOException(ServerUtil.java:88) > at org.apache.phoenix.util.ServerUtil.throwIOException(ServerUtil.java:62) > at org.apache.phoenix.index.PhoenixIndexMetaData.getIndexMetaData(PhoenixIndexMetaData.java:87) > at org.apache.phoenix.index.PhoenixIndexMetaData.(PhoenixIndexMetaData.java:103) > at org.apache.phoenix.index.PhoenixIndexBuilder.getIndexMetaData(PhoenixIndexBuilder.java:95) > at org.apache.phoenix.hbase.index.builder.IndexBuildManager.getIndexUpdate(IndexBuildManager.java:80) > at org.apache.phoenix.hbase.index.Indexer.preBatchMutateWithExceptions(Indexer.java:528) > at org.apache.phoenix.hbase.index.Indexer.preBatchMutate(Indexer.java:374) > at org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost$37.call(RegionCoprocessorHost.java:1032) > at org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost$RegionOperation.call(RegionCoprocessorHost.java:1714) > at org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost.execOperation(RegionCoprocessorHost.java:1789) > at org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost.execOperation(RegionCoprocessorHost.java:1746) > at org.apache.hadoop.hbase.regionserver.RegionCoprocessorHost.preBatchMutate(RegionCoprocessorHost.java:1028) > at org.apache.hadoop.hbase.regionserver.HRegion.asyncBatchMutate(HRegion.java:3236) > at org.apache.hadoop.hbase.regionserver.RSRpcServices.doAsyncBatchOp(RSRpcServices.java:2147) > at org.apache.hadoop.hbase.regionserver.RSRpcServices.doNonAtomicBatchMutationCrossRegions(RSRpcServices.java:2308) > at org.apache.hadoop.hbase.regionserver.RSRpcServices.multi(RSRpcServices.java:2578) > at org.apache.hadoop.hbase.protobuf.generated.ClientProtos$ClientService$2.callBlockingMethod(ClientProtos.java:32303) > at org.apache.hadoop.hbase.ipc.RpcServer.call(RpcServer.java:2394) > at org.apache.hadoop.hbase.ipc.CallRunner.run(CallRunner.java:174) > at org.apache.hadoop.hbase.ipc.RpcExecutor$CallHandler.run(RpcExecutor.java:178) > Caused by: java.sql.SQLException: ERROR 2008 (INT10): Unable to find cached index metadata. key=-727998515684050837 region=LOCK,\x0E,1537434393195.f4de29d4b36775589a49f1c7a20c73a2.host=hb-bp1v2q830426r > 6763-004.hbase.rds.aliyuncs.com,16020,1537434304031 > at org.apache.phoenix.exception.SQLExceptionCode$Factory$1.newException(SQLExceptionCode.java:493) > at org.apache.phoenix.exception.SQLExceptionInfo.buildException(SQLExceptionInfo.java:150) > at org.apache.phoenix.index.PhoenixIndexMetaData.getIndexMetaData(PhoenixIndexMetaData.java:85) > {code} > -- This message was sent by Atlassian JIRA (v7.6.3#76005)