Return-Path: X-Original-To: archive-asf-public-internal@cust-asf2.ponee.io Delivered-To: archive-asf-public-internal@cust-asf2.ponee.io Received: from cust-asf.ponee.io (cust-asf.ponee.io [163.172.22.183]) by cust-asf2.ponee.io (Postfix) with ESMTP id C2470200D31 for ; Sat, 21 Oct 2017 02:34:13 +0200 (CEST) Received: by cust-asf.ponee.io (Postfix) id C0A8E160BED; Sat, 21 Oct 2017 00:34:13 +0000 (UTC) Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by cust-asf.ponee.io (Postfix) with SMTP id F0F05160BCB for ; Sat, 21 Oct 2017 02:34:11 +0200 (CEST) Received: (qmail 23888 invoked by uid 500); 21 Oct 2017 00:34:11 -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 23876 invoked by uid 99); 21 Oct 2017 00:34:11 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd3-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Sat, 21 Oct 2017 00:34:11 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd3-us-west.apache.org (ASF Mail Server at spamd3-us-west.apache.org) with ESMTP id 2670B1807E5 for ; Sat, 21 Oct 2017 00:34:10 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd3-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: -98.952 X-Spam-Level: X-Spam-Status: No, score=-98.952 tagged_above=-999 required=6.31 tests=[KAM_ASCII_DIVIDERS=0.8, KAM_LOTSOFHASH=0.25, RP_MATCHES_RCVD=-0.001, SPF_PASS=-0.001, USER_IN_WHITELIST=-100] autolearn=disabled Received: from mx1-lw-eu.apache.org ([10.40.0.8]) by localhost (spamd3-us-west.apache.org [10.40.0.10]) (amavisd-new, port 10024) with ESMTP id NDcx2YDb9pPu for ; Sat, 21 Oct 2017 00:34: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 A1CF95F5CC for ; Sat, 21 Oct 2017 00:34: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 C09FFE0EE9 for ; Sat, 21 Oct 2017 00:34: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 79D6E24360 for ; Sat, 21 Oct 2017 00:34:00 +0000 (UTC) Date: Sat, 21 Oct 2017 00:34:00 +0000 (UTC) From: "Samarth Jain (JIRA)" To: dev@phoenix.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Commented] (PHOENIX-4289) UPDATE STATISTICS command does not collect stats for local indexes MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 7bit X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 archived-at: Sat, 21 Oct 2017 00:34:13 -0000 [ https://issues.apache.org/jira/browse/PHOENIX-4289?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=16213580#comment-16213580 ] Samarth Jain commented on PHOENIX-4289: --------------------------------------- [~mujtabachohan], I am unable to repro this issue in a unit test. This is what I added in ExplainPlanWithStatsEnabledIT: {code} @Test public void testEstimatesWithLocalIndexes() throws Exception { String tableName = generateUniqueName(); String indexName = "IDX_" + generateUniqueName(); try (Connection conn = DriverManager.getConnection(getUrl())) { int guidePostWidth = 20; conn.createStatement() .execute("CREATE TABLE " + tableName + " (k INTEGER PRIMARY KEY, a bigint, b bigint)" + " GUIDE_POSTS_WIDTH=" + guidePostWidth); conn.createStatement().execute("upsert into " + tableName + " values (100,1,3)"); conn.createStatement().execute("upsert into " + tableName + " values (101,2,4)"); conn.createStatement().execute("upsert into " + tableName + " values (102,2,4)"); conn.createStatement().execute("upsert into " + tableName + " values (103,2,4)"); conn.createStatement().execute("upsert into " + tableName + " values (104,2,4)"); conn.createStatement().execute("upsert into " + tableName + " values (105,2,4)"); conn.createStatement().execute("upsert into " + tableName + " values (106,2,4)"); conn.createStatement().execute("upsert into " + tableName + " values (107,2,4)"); conn.createStatement().execute("upsert into " + tableName + " values (108,2,4)"); conn.createStatement().execute("upsert into " + tableName + " values (109,2,4)"); conn.commit(); conn.createStatement().execute( "CREATE LOCAL INDEX " + indexName + " ON " + tableName + " (a) INCLUDE (b) "); conn.createStatement().execute("UPDATE STATISTICS " + tableName + ""); } List binds = Lists.newArrayList(); try (Connection conn = DriverManager.getConnection(getUrl())) { String sql = "SELECT COUNT(*) " + " FROM " + tableName; ResultSet rs = conn.createStatement().executeQuery(sql); assertTrue("Index " + indexName + " should have been used", rs.unwrap(PhoenixResultSet.class).getStatement().getQueryPlan().getTableRef() .getTable().getName().getString().equals(indexName)); Estimate info = getByteRowEstimates(conn, sql, binds); assertEquals((Long) 10l, info.estimatedRows); assertTrue(info.estimateInfoTs > 0); } } {code} > UPDATE STATISTICS command does not collect stats for local indexes > ------------------------------------------------------------------ > > Key: PHOENIX-4289 > URL: https://issues.apache.org/jira/browse/PHOENIX-4289 > Project: Phoenix > Issue Type: Bug > Affects Versions: 4.12.0 > Environment: HBase 1.3.1, Phoenix 4.12.0 > Reporter: Mujtaba Chohan > Assignee: Samarth Jain > Labels: localIndex > > With clean {{SYSTEM.STATS}} table and restarted HBase server+Phoenix client. Ran {{UPDATE STATISTICS T ALL}} command. Global guidepost width is set to 100M. No stats are generated for any of the local indexes on table T. > {noformat} > explain select count(*) from T; > +---------------------------------------------------------------------------+-----------------+----------------+--------------+ > | PLAN | EST_BYTES_READ | EST_ROWS_READ | EST_INFO_TS | > +---------------------------------------------------------------------------+-----------------+----------------+--------------+ > | CLIENT 8-CHUNK PARALLEL 8-WAY RANGE SCAN OVER T [1] | null | null | null | > | SERVER FILTER BY FIRST KEY ONLY | null | null | null | > | SERVER AGGREGATE INTO SINGLE ROW | null | null | null | > +---------------------------------------------------------------------------+-----------------+----------------+--------------+ > select * from system.stats; > +------------------------------+----------------+-----------------+--------------------+--------------------------+------------------------+ > | PHYSICAL_NAME | COLUMN_FAMILY | GUIDE_POST_KEY | GUIDE_POSTS_WIDTH | LAST_STATS_UPDATE_TIME | GUIDE_POSTS_ROW_COUNT | > +------------------------------+----------------+-----------------+--------------------+--------------------------+------------------------+ > | T | | | null | 2017-10-16 18:36:57.884 | null | > | T | 0 | [B@9bd0fa6 | 100000099 | | 75756 | > | T | 0 | [B@59d2103b | 100000057 | | 75748 | > | T | 0 | [B@39dcf4b0 | 100000058 | | 75748 | > | T | 0 | [B@6e4de19b | 100000081 | | 75743 | > | T | 0 | [B@f6c03cb | 100000044 | | 75744 | > | T | 0 | [B@46f699d5 | 100000023 | | 75741 | > | T | 0 | [B@18518ccf | 100000019 | | 75749 | > | T | 0 | [B@1991f767 | 100000097 | | 75740 | > | T | 0 | [B@768ccdc5 | 100000092 | | 75740 | > | T | 0 | [B@4c6daf0 | 100000026 | | 75739 | > | T | 0 | [B@10650953 | 100000054 | | 75731 | > | T | 0 | [B@659eef7 | 100000092 | | 75741 | > | T | 0 | [B@162be91c | 100000023 | | 75752 | > | T | 0 | [B@2488b073 | 100000096 | | 75743 | > | T | 0 | [B@1c9f0a20 | 100000025 | | 75745 | > | T | 0 | [B@55787112 | 100000104 | | 75725 | > | T | 0 | [B@1cd201a8 | 100000019 | | 75748 | > | T | 0 | [B@7db82169 | 100000080 | | 75740 | > | T | 0 | [B@1992eaf4 | 100000079 | | 75733 | > | T | 0 | [B@f74e835 | 100000003 | | 75746 | > | T | 0 | [B@3276732 | 100000118 | | 75750 | > | T | 0 | [B@3f28bd56 | 100000019 | | 75749 | > | T | 0 | [B@31e3250d | 100000091 | | 75753 | > | T | 0 | [B@19fe4644 | 100000088 | | 75747 | > | T | 0 | [B@21d8bcbe | 100000000 | | 75738 | > | T | 0 | [B@5be067de | 100000036 | | 75742 | > | T | 0 | [B@7383eae2 | 100000105 | | 75745 | > | T | 0 | [B@18245eb0 | 100000056 | | 75741 | > | T | 0 | [B@7c7d3c46 | 100000103 | | 75746 | > | T | 0 | [B@24fb6a80 | 100000027 | | 75738 | > | T | 0 | [B@48c35007 | 100000084 | | 75736 | > | T | 0 | [B@72a85671 | 100000048 | | 75735 | > | T | 0 | [B@6722db6e | 100000051 | | 75758 | > | T | 0 | [B@18f20260 | 100000015 | | 75750 | > | T | 0 | [B@4ae33a11 | 100000052 | | 75742 | > | T | 0 | [B@7a48e6e2 | 100000029 | | 75741 | > | T | 0 | [B@b40bb6e | 100000006 | | 75735 | > | T | 0 | [B@3a94964 | 100000098 | | 75732 | > | T | 0 | [B@5049d8b2 | 100000024 | | 75760 | > | T | 0 | [B@6d0b5baf | 100000103 | | 75751 | > | T | 0 | [B@631e06ab | 100000022 | | 75744 | > | T | 0 | [B@2a3591c5 | 100000000 | | 75746 | > | T | 0 | [B@34a75079 | 100000107 | | 75742 | > | T | 0 | [B@346a361 | 100000111 | | 75738 | > | T | 0 | [B@107ed6fc | 100000037 | | 75744 | > | T | 0 | [B@1643d68f | 100000001 | | 75743 | > | T | 0 | [B@186978a6 | 100000082 | | 75743 | > | T | 0 | [B@2e029d61 | 100000024 | | 75736 | > | T | 0 | [B@482d776b | 100000036 | | 75742 | > | T | 0 | [B@4052274f | 100000076 | | 75745 | > | T | 0 | [B@132ddbab | 100000011 | | 75743 | > | T | 0 | [B@297ea53a | 100000085 | | 75746 | > | T | 0 | [B@acb0951 | 100000006 | | 75745 | > | T | 0 | [B@5bf22f18 | 100000084 | | 75742 | > | T | 0 | [B@267f474e | 100000087 | | 75730 | > | T | 0 | [B@7a7471ce | 100000118 | | 75758 | > | T | 0 | [B@28276e50 | 100000119 | | 75740 | > | T | 0 | [B@62e70ea3 | 100000048 | | 75734 | > | T | 0 | [B@3efe7086 | 100000101 | | 75755 | > | T | 0 | [B@675d8c96 | 100000022 | | 75740 | > | T | 0 | [B@741b3bc3 | 100000100 | | 75755 | > | T | 0 | [B@2ed3b1f5 | 100000001 | | 75761 | > | T | 0 | [B@63648ee9 | 100000037 | | 75738 | > | T | 0 | [B@68d6972f | 100000092 | | 75745 | > | _IDX_T | | | null | 2017-10-16 18:36:56.286 | null | > | _IDX_T | 0 | | 100000000 | | 0 | > +------------------------------+----------------+-----------------+--------------------+--------------------------+------------------------+ > {noformat} > Server logs > {noformat} > 2017-10-16 11:36:48,889 INFO [RpcServer.FifoWFPBQ.default.handler=29,queue=2,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId2feedEntityId0040F900000000X426G\x00networkId072427userId000072428,1507937386171.c293d3006ba45335660b49a155e52e7b. > 2017-10-16 11:36:48,889 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxnFactory: Accepted socket connection from /127.0.0.1:56475 > 2017-10-16 11:36:48,889 INFO [RpcServer.FifoWFPBQ.default.handler=28,queue=1,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId2feedEntityId0060F900000000X456G\x00networkId006457userId000006458,1507937386710.d6919e3083dba9e8065029a758ec1b59. > 2017-10-16 11:36:48,889 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.ZooKeeperServer: Client attempting to establish new session at /127.0.0.1:56475 > 2017-10-16 11:36:48,889 INFO [RpcServer.FifoWFPBQ.default.handler=26,queue=2,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId2feedEntityId0020F900000000X242G\x00networkId001243userId000001244,1507937385505.87e92e6c9b42e993bdc5f41e33d4cbe3. > 2017-10-16 11:36:48,890 INFO [RpcServer.FifoWFPBQ.default.handler=26,queue=2,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,,1507937384045.c18bbd1228f667abcfe758395118f217. > 2017-10-16 11:36:48,891 INFO [RpcServer.FifoWFPBQ.default.handler=29,queue=2,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId1feedEntityId0030F900000000X499G\x00networkId011,1507937384045.633128bd9bcf4a0839a57c520595d37d. > 2017-10-16 11:36:48,892 INFO [RpcServer.FifoWFPBQ.default.handler=23,queue=2,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId2feedEntityId0070F900000000X235G\x00networkId050236userId000050237,1507937386710.62912e7f0f090230666cbe530dfad4a2. > 2017-10-16 11:36:48,892 INFO [RpcServer.FifoWFPBQ.default.handler=29,queue=2,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId2feedEntityId0050F900000000X470G\x00networkId004471userId000004472,1507937386171.53ec9e2feb987147a02d02c1c1906ae2. > 2017-10-16 11:36:48,892 INFO [RpcServer.FifoWFPBQ.default.handler=27,queue=0,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId1feedEntityId0060F900000000X960G\x00networkId088961userId000088962,1507937385505.482b058b7c99d31489992be5a43ac7b4. > 2017-10-16 11:36:48,897 INFO [SyncThread:0] server.ZooKeeperServer: Established session 0x15f2678fade000f with negotiated timeout 10000 for client /127.0.0.1:56472 > 2017-10-16 11:36:48,897 INFO [phoenix-update-statistics-3-SendThread(localhost:2181)] zookeeper.ClientCnxn: Session establishment complete on server localhost/127.0.0.1:2181, sessionid = 0x15f2678fade000f, negotiated timeout = 10000 > 2017-10-16 11:36:48,898 INFO [SyncThread:0] server.ZooKeeperServer: Established session 0x15f2678fade0010 with negotiated timeout 10000 for client /127.0.0.1:56473 > 2017-10-16 11:36:48,898 INFO [phoenix-update-statistics-0-SendThread(localhost:2181)] zookeeper.ClientCnxn: Session establishment complete on server localhost/127.0.0.1:2181, sessionid = 0x15f2678fade0010, negotiated timeout = 10000 > 2017-10-16 11:36:48,898 INFO [SyncThread:0] server.ZooKeeperServer: Established session 0x15f2678fade0011 with negotiated timeout 10000 for client /127.0.0.1:56475 > 2017-10-16 11:36:48,898 INFO [phoenix-update-statistics-2-SendThread(localhost:2181)] zookeeper.ClientCnxn: Session establishment complete on server localhost/127.0.0.1:2181, sessionid = 0x15f2678fade0011, negotiated timeout = 10000 > 2017-10-16 11:36:48,906 INFO [RpcServer.FifoWFPBQ.default.handler=27,queue=0,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId2feedEntityId0050F900000000X470G\x00networkId004471userId000004472,1507937386171.53ec9e2feb987147a02d02c1c1906ae2. > 2017-10-16 11:36:48,907 INFO [RpcServer.FifoWFPBQ.default.handler=23,queue=2,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId2feedEntityId0060F900000000X456G\x00networkId006457userId000006458,1507937386710.d6919e3083dba9e8065029a758ec1b59. > 2017-10-16 11:36:48,907 INFO [RpcServer.FifoWFPBQ.default.handler=27,queue=0,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,,1507937384045.c18bbd1228f667abcfe758395118f217. > 2017-10-16 11:36:48,907 INFO [RpcServer.FifoWFPBQ.default.handler=22,queue=1,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId2feedEntityId0040F900000000X426G\x00networkId072427userId000072428,1507937386171.c293d3006ba45335660b49a155e52e7b. > 2017-10-16 11:36:48,910 INFO [RpcServer.FifoWFPBQ.default.handler=22,queue=1,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId1feedEntityId0060F900000000X960G\x00networkId088961userId000088962,1507937385505.482b058b7c99d31489992be5a43ac7b4. > 2017-10-16 11:36:48,910 INFO [RpcServer.FifoWFPBQ.default.handler=26,queue=2,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId2feedEntityId0020F900000000X242G\x00networkId001243userId000001244,1507937385505.87e92e6c9b42e993bdc5f41e33d4cbe3. > 2017-10-16 11:36:48,914 INFO [RpcServer.FifoWFPBQ.default.handler=26,queue=2,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId2feedEntityId0070F900000000X235G\x00networkId050236userId000050237,1507937386710.62912e7f0f090230666cbe530dfad4a2. > 2017-10-16 11:36:48,919 INFO [RpcServer.FifoWFPBQ.default.handler=29,queue=2,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId1feedEntityId0030F900000000X499G\x00networkId011,1507937384045.633128bd9bcf4a0839a57c520595d37d. > 2017-10-16 11:36:48,967 INFO [RpcServer.FifoWFPBQ.default.handler=22,queue=1,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId2feedEntityId0050F900000000X470G\x00networkId004471userId000004472,1507937386171.53ec9e2feb987147a02d02c1c1906ae2. > 2017-10-16 11:36:48,969 INFO [RpcServer.FifoWFPBQ.default.handler=29,queue=2,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId1feedEntityId0030F900000000X499G\x00networkId011,1507937384045.633128bd9bcf4a0839a57c520595d37d. > 2017-10-16 11:36:48,981 INFO [RpcServer.FifoWFPBQ.default.handler=29,queue=2,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,,1507937384045.c18bbd1228f667abcfe758395118f217. > 2017-10-16 11:36:48,982 INFO [RpcServer.FifoWFPBQ.default.handler=22,queue=1,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId2feedEntityId0020F900000000X242G\x00networkId001243userId000001244,1507937385505.87e92e6c9b42e993bdc5f41e33d4cbe3. > 2017-10-16 11:36:48,982 INFO [RpcServer.FifoWFPBQ.default.handler=22,queue=1,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId2feedEntityId0040F900000000X426G\x00networkId072427userId000072428,1507937386171.c293d3006ba45335660b49a155e52e7b. > 2017-10-16 11:36:48,983 INFO [RpcServer.FifoWFPBQ.default.handler=22,queue=1,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId2feedEntityId0070F900000000X235G\x00networkId050236userId000050237,1507937386710.62912e7f0f090230666cbe530dfad4a2. > 2017-10-16 11:36:48,985 INFO [RpcServer.FifoWFPBQ.default.handler=22,queue=1,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId2feedEntityId0060F900000000X456G\x00networkId006457userId000006458,1507937386710.d6919e3083dba9e8065029a758ec1b59. > 2017-10-16 11:36:48,992 INFO [RpcServer.FifoWFPBQ.default.handler=22,queue=1,port=25946] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS didn't run because another UPDATE STATISTICS command was already running on the region T,organizationId1feedEntityId0060F900000000X960G\x00networkId088961userId000088962,1507937385505.482b058b7c99d31489992be5a43ac7b4. > 2017-10-16 11:36:52,436 INFO [phoenix-update-statistics-0] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS finished successfully for scanner: org.apache.hadoop.hbase.regionserver.HRegion$RegionScannerImpl@777b14da. Number of rows scanned: 402553. Time: 3638 > 2017-10-16 11:36:52,436 INFO [phoenix-update-statistics-0] client.ConnectionManager$HConnectionImplementation: Closing zookeeper sessionid=0x15f2678fade0010 > 2017-10-16 11:36:52,436 INFO [ProcessThread(sid:0 cport:-1):] server.PrepRequestProcessor: Processed session termination for sessionid: 0x15f2678fade0010 > 2017-10-16 11:36:52,443 INFO [phoenix-update-statistics-0] zookeeper.ZooKeeper: Session: 0x15f2678fade0010 closed > 2017-10-16 11:36:52,443 INFO [phoenix-update-statistics-0-EventThread] zookeeper.ClientCnxn: EventThread shut down > 2017-10-16 11:36:52,444 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxn: Closed socket connection for client /127.0.0.1:56473 which had sessionid 0x15f2678fade0010 > 2017-10-16 11:36:52,445 INFO [phoenix-update-statistics-0] zookeeper.RecoverableZooKeeper: Process identifier=hconnection-0x63c6bea connecting to ZooKeeper ensemble=localhost:2181 > 2017-10-16 11:36:52,445 INFO [phoenix-update-statistics-0] zookeeper.ZooKeeper: Initiating client connection, connectString=localhost:2181 sessionTimeout=10000 watcher=org.apache.hadoop.hbase.zookeeper.PendingWatcher@d2c57a5 > 2017-10-16 11:36:52,446 INFO [phoenix-update-statistics-0-SendThread(localhost:2181)] zookeeper.ClientCnxn: Opening socket connection to server localhost/127.0.0.1:2181. Will not attempt to authenticate using SASL (unknown error) > 2017-10-16 11:36:52,446 INFO [phoenix-update-statistics-0-SendThread(localhost:2181)] zookeeper.ClientCnxn: Socket connection established to localhost/127.0.0.1:2181, initiating session > 2017-10-16 11:36:52,446 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxnFactory: Accepted socket connection from /127.0.0.1:56476 > 2017-10-16 11:36:52,447 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.ZooKeeperServer: Client attempting to establish new session at /127.0.0.1:56476 > 2017-10-16 11:36:52,450 INFO [SyncThread:0] server.ZooKeeperServer: Established session 0x15f2678fade0012 with negotiated timeout 10000 for client /127.0.0.1:56476 > 2017-10-16 11:36:52,450 INFO [phoenix-update-statistics-0-SendThread(localhost:2181)] zookeeper.ClientCnxn: Session establishment complete on server localhost/127.0.0.1:2181, sessionid = 0x15f2678fade0012, negotiated timeout = 10000 > 2017-10-16 11:36:52,455 INFO [phoenix-update-statistics-0] client.ConnectionManager$HConnectionImplementation: Closing zookeeper sessionid=0x15f2678fade0012 > 2017-10-16 11:36:52,455 INFO [ProcessThread(sid:0 cport:-1):] server.PrepRequestProcessor: Processed session termination for sessionid: 0x15f2678fade0012 > 2017-10-16 11:36:52,461 INFO [phoenix-update-statistics-0] zookeeper.ZooKeeper: Session: 0x15f2678fade0012 closed > 2017-10-16 11:36:52,462 INFO [phoenix-update-statistics-0-EventThread] zookeeper.ClientCnxn: EventThread shut down > 2017-10-16 11:36:52,462 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxn: Closed socket connection for client /127.0.0.1:56476 which had sessionid 0x15f2678fade0012 > 2017-10-16 11:36:52,463 INFO [phoenix-update-statistics-0] zookeeper.RecoverableZooKeeper: Process identifier=hconnection-0x472d7532 connecting to ZooKeeper ensemble=localhost:2181 > 2017-10-16 11:36:52,463 INFO [phoenix-update-statistics-0] zookeeper.ZooKeeper: Initiating client connection, connectString=localhost:2181 sessionTimeout=10000 watcher=org.apache.hadoop.hbase.zookeeper.PendingWatcher@257f0e65 > 2017-10-16 11:36:52,464 INFO [phoenix-update-statistics-0-SendThread(localhost:2181)] zookeeper.ClientCnxn: Opening socket connection to server localhost/127.0.0.1:2181. Will not attempt to authenticate using SASL (unknown error) > 2017-10-16 11:36:52,464 INFO [phoenix-update-statistics-0-SendThread(localhost:2181)] zookeeper.ClientCnxn: Socket connection established to localhost/127.0.0.1:2181, initiating session > 2017-10-16 11:36:52,464 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxnFactory: Accepted socket connection from /127.0.0.1:56477 > 2017-10-16 11:36:52,465 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.ZooKeeperServer: Client attempting to establish new session at /127.0.0.1:56477 > 2017-10-16 11:36:52,468 INFO [SyncThread:0] server.ZooKeeperServer: Established session 0x15f2678fade0013 with negotiated timeout 10000 for client /127.0.0.1:56477 > 2017-10-16 11:36:52,468 INFO [phoenix-update-statistics-0-SendThread(localhost:2181)] zookeeper.ClientCnxn: Session establishment complete on server localhost/127.0.0.1:2181, sessionid = 0x15f2678fade0013, negotiated timeout = 10000 > 2017-10-16 11:36:53,095 INFO [phoenix-update-statistics-1] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS finished successfully for scanner: org.apache.hadoop.hbase.regionserver.HRegion$RegionScannerImpl@74db5466. Number of rows scanned: 677858. Time: 4296 > 2017-10-16 11:36:53,095 INFO [phoenix-update-statistics-1] client.ConnectionManager$HConnectionImplementation: Closing zookeeper sessionid=0x15f2678fade000e > 2017-10-16 11:36:53,095 INFO [ProcessThread(sid:0 cport:-1):] server.PrepRequestProcessor: Processed session termination for sessionid: 0x15f2678fade000e > 2017-10-16 11:36:53,108 INFO [phoenix-update-statistics-1] zookeeper.ZooKeeper: Session: 0x15f2678fade000e closed > 2017-10-16 11:36:53,108 INFO [phoenix-update-statistics-1-EventThread] zookeeper.ClientCnxn: EventThread shut down > 2017-10-16 11:36:53,109 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxn: Closed socket connection for client /127.0.0.1:56471 which had sessionid 0x15f2678fade000e > 2017-10-16 11:36:53,110 INFO [phoenix-update-statistics-1] zookeeper.RecoverableZooKeeper: Process identifier=hconnection-0x762f171e connecting to ZooKeeper ensemble=localhost:2181 > 2017-10-16 11:36:53,110 INFO [phoenix-update-statistics-1] zookeeper.ZooKeeper: Initiating client connection, connectString=localhost:2181 sessionTimeout=10000 watcher=org.apache.hadoop.hbase.zookeeper.PendingWatcher@31538fa > 2017-10-16 11:36:53,111 INFO [phoenix-update-statistics-1-SendThread(localhost:2181)] zookeeper.ClientCnxn: Opening socket connection to server localhost/127.0.0.1:2181. Will not attempt to authenticate using SASL (unknown error) > 2017-10-16 11:36:53,111 INFO [phoenix-update-statistics-1-SendThread(localhost:2181)] zookeeper.ClientCnxn: Socket connection established to localhost/127.0.0.1:2181, initiating session > 2017-10-16 11:36:53,111 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxnFactory: Accepted socket connection from /127.0.0.1:56478 > 2017-10-16 11:36:53,112 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.ZooKeeperServer: Client attempting to establish new session at /127.0.0.1:56478 > 2017-10-16 11:36:53,115 INFO [phoenix-update-statistics-2] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS finished successfully for scanner: org.apache.hadoop.hbase.regionserver.HRegion$RegionScannerImpl@15e895ec. Number of rows scanned: 669996. Time: 4315 > 2017-10-16 11:36:53,115 INFO [phoenix-update-statistics-2] client.ConnectionManager$HConnectionImplementation: Closing zookeeper sessionid=0x15f2678fade0011 > 2017-10-16 11:36:53,115 INFO [ProcessThread(sid:0 cport:-1):] server.PrepRequestProcessor: Processed session termination for sessionid: 0x15f2678fade0011 > 2017-10-16 11:36:53,118 INFO [SyncThread:0] server.ZooKeeperServer: Established session 0x15f2678fade0014 with negotiated timeout 10000 for client /127.0.0.1:56478 > 2017-10-16 11:36:53,118 INFO [phoenix-update-statistics-1-SendThread(localhost:2181)] zookeeper.ClientCnxn: Session establishment complete on server localhost/127.0.0.1:2181, sessionid = 0x15f2678fade0014, negotiated timeout = 10000 > 2017-10-16 11:36:53,124 INFO [phoenix-update-statistics-2] zookeeper.ZooKeeper: Session: 0x15f2678fade0011 closed > 2017-10-16 11:36:53,124 INFO [phoenix-update-statistics-2-EventThread] zookeeper.ClientCnxn: EventThread shut down > 2017-10-16 11:36:53,125 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxn: Closed socket connection for client /127.0.0.1:56475 which had sessionid 0x15f2678fade0011 > 2017-10-16 11:36:53,125 INFO [phoenix-update-statistics-2] zookeeper.RecoverableZooKeeper: Process identifier=hconnection-0x430d41ca connecting to ZooKeeper ensemble=localhost:2181 > 2017-10-16 11:36:53,125 INFO [phoenix-update-statistics-2] zookeeper.ZooKeeper: Initiating client connection, connectString=localhost:2181 sessionTimeout=10000 watcher=org.apache.hadoop.hbase.zookeeper.PendingWatcher@10ba1f96 > 2017-10-16 11:36:53,126 INFO [phoenix-update-statistics-2-SendThread(localhost:2181)] zookeeper.ClientCnxn: Opening socket connection to server localhost/127.0.0.1:2181. Will not attempt to authenticate using SASL (unknown error) > 2017-10-16 11:36:53,126 INFO [phoenix-update-statistics-2-SendThread(localhost:2181)] zookeeper.ClientCnxn: Socket connection established to localhost/127.0.0.1:2181, initiating session > 2017-10-16 11:36:53,126 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxnFactory: Accepted socket connection from /127.0.0.1:56479 > 2017-10-16 11:36:53,128 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.ZooKeeperServer: Client attempting to establish new session at /127.0.0.1:56479 > 2017-10-16 11:36:53,132 INFO [SyncThread:0] server.ZooKeeperServer: Established session 0x15f2678fade0015 with negotiated timeout 10000 for client /127.0.0.1:56479 > 2017-10-16 11:36:53,132 INFO [phoenix-update-statistics-2-SendThread(localhost:2181)] zookeeper.ClientCnxn: Session establishment complete on server localhost/127.0.0.1:2181, sessionid = 0x15f2678fade0015, negotiated timeout = 10000 > 2017-10-16 11:36:53,136 INFO [phoenix-update-statistics-1] client.ConnectionManager$HConnectionImplementation: Closing zookeeper sessionid=0x15f2678fade0014 > 2017-10-16 11:36:53,136 INFO [phoenix-update-statistics-2] client.ConnectionManager$HConnectionImplementation: Closing zookeeper sessionid=0x15f2678fade0015 > 2017-10-16 11:36:53,136 INFO [ProcessThread(sid:0 cport:-1):] server.PrepRequestProcessor: Processed session termination for sessionid: 0x15f2678fade0015 > 2017-10-16 11:36:53,136 INFO [ProcessThread(sid:0 cport:-1):] server.PrepRequestProcessor: Processed session termination for sessionid: 0x15f2678fade0014 > 2017-10-16 11:36:53,142 INFO [phoenix-update-statistics-2] zookeeper.ZooKeeper: Session: 0x15f2678fade0015 closed > 2017-10-16 11:36:53,142 INFO [phoenix-update-statistics-1] zookeeper.ZooKeeper: Session: 0x15f2678fade0014 closed > 2017-10-16 11:36:53,142 INFO [phoenix-update-statistics-1-EventThread] zookeeper.ClientCnxn: EventThread shut down > 2017-10-16 11:36:53,142 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxn: Closed socket connection for client /127.0.0.1:56479 which had sessionid 0x15f2678fade0015 > 2017-10-16 11:36:53,143 WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxn: caught end of stream exception > EndOfStreamException: Unable to read additional data from client sessionid 0x15f2678fade0014, likely client has closed socket > at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) > at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) > at java.lang.Thread.run(Thread.java:745) > 2017-10-16 11:36:53,143 INFO [phoenix-update-statistics-2] zookeeper.RecoverableZooKeeper: Process identifier=hconnection-0x182b2f8 connecting to ZooKeeper ensemble=localhost:2181 > 2017-10-16 11:36:53,143 INFO [phoenix-update-statistics-2-EventThread] zookeeper.ClientCnxn: EventThread shut down > 2017-10-16 11:36:53,143 INFO [phoenix-update-statistics-1] zookeeper.RecoverableZooKeeper: Process identifier=hconnection-0x40ea005c connecting to ZooKeeper ensemble=localhost:2181 > 2017-10-16 11:36:53,143 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxn: Closed socket connection for client /127.0.0.1:56478 which had sessionid 0x15f2678fade0014 > 2017-10-16 11:36:53,143 INFO [phoenix-update-statistics-2] zookeeper.ZooKeeper: Initiating client connection, connectString=localhost:2181 sessionTimeout=10000 watcher=org.apache.hadoop.hbase.zookeeper.PendingWatcher@331f24f9 > 2017-10-16 11:36:53,144 INFO [phoenix-update-statistics-1] zookeeper.ZooKeeper: Initiating client connection, connectString=localhost:2181 sessionTimeout=10000 watcher=org.apache.hadoop.hbase.zookeeper.PendingWatcher@513dd5ca > 2017-10-16 11:36:53,145 INFO [phoenix-update-statistics-2-SendThread(localhost:2181)] zookeeper.ClientCnxn: Opening socket connection to server localhost/127.0.0.1:2181. Will not attempt to authenticate using SASL (unknown error) > 2017-10-16 11:36:53,145 INFO [phoenix-update-statistics-1-SendThread(localhost:2181)] zookeeper.ClientCnxn: Opening socket connection to server localhost/127.0.0.1:2181. Will not attempt to authenticate using SASL (unknown error) > 2017-10-16 11:36:53,145 INFO [phoenix-update-statistics-2-SendThread(localhost:2181)] zookeeper.ClientCnxn: Socket connection established to localhost/127.0.0.1:2181, initiating session > 2017-10-16 11:36:53,145 INFO [phoenix-update-statistics-1-SendThread(localhost:2181)] zookeeper.ClientCnxn: Socket connection established to localhost/127.0.0.1:2181, initiating session > 2017-10-16 11:36:53,145 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxnFactory: Accepted socket connection from /127.0.0.1:56481 > 2017-10-16 11:36:53,145 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.ZooKeeperServer: Client attempting to establish new session at /127.0.0.1:56481 > 2017-10-16 11:36:53,145 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxnFactory: Accepted socket connection from /127.0.0.1:56480 > 2017-10-16 11:36:53,145 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.ZooKeeperServer: Client attempting to establish new session at /127.0.0.1:56480 > 2017-10-16 11:36:53,150 INFO [SyncThread:0] server.ZooKeeperServer: Established session 0x15f2678fade0016 with negotiated timeout 10000 for client /127.0.0.1:56481 > 2017-10-16 11:36:53,150 INFO [phoenix-update-statistics-2-SendThread(localhost:2181)] zookeeper.ClientCnxn: Session establishment complete on server localhost/127.0.0.1:2181, sessionid = 0x15f2678fade0016, negotiated timeout = 10000 > 2017-10-16 11:36:53,156 INFO [SyncThread:0] server.ZooKeeperServer: Established session 0x15f2678fade0017 with negotiated timeout 10000 for client /127.0.0.1:56480 > 2017-10-16 11:36:53,156 INFO [phoenix-update-statistics-1-SendThread(localhost:2181)] zookeeper.ClientCnxn: Session establishment complete on server localhost/127.0.0.1:2181, sessionid = 0x15f2678fade0017, negotiated timeout = 10000 > 2017-10-16 11:36:55,513 INFO [phoenix-update-statistics-2] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS finished successfully for scanner: org.apache.hadoop.hbase.regionserver.HRegion$RegionScannerImpl@203008bf. Number of rows scanned: 372558. Time: 2389 > 2017-10-16 11:36:55,513 INFO [phoenix-update-statistics-2] client.ConnectionManager$HConnectionImplementation: Closing zookeeper sessionid=0x15f2678fade0016 > 2017-10-16 11:36:55,513 INFO [ProcessThread(sid:0 cport:-1):] server.PrepRequestProcessor: Processed session termination for sessionid: 0x15f2678fade0016 > 2017-10-16 11:36:55,526 INFO [phoenix-update-statistics-2] zookeeper.ZooKeeper: Session: 0x15f2678fade0016 closed > 2017-10-16 11:36:55,526 INFO [phoenix-update-statistics-2-EventThread] zookeeper.ClientCnxn: EventThread shut down > 2017-10-16 11:36:55,526 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxn: Closed socket connection for client /127.0.0.1:56481 which had sessionid 0x15f2678fade0016 > 2017-10-16 11:36:55,526 INFO [phoenix-update-statistics-2] zookeeper.RecoverableZooKeeper: Process identifier=hconnection-0x3364227e connecting to ZooKeeper ensemble=localhost:2181 > 2017-10-16 11:36:55,526 INFO [phoenix-update-statistics-2] zookeeper.ZooKeeper: Initiating client connection, connectString=localhost:2181 sessionTimeout=10000 watcher=org.apache.hadoop.hbase.zookeeper.PendingWatcher@6ae03297 > 2017-10-16 11:36:55,531 INFO [phoenix-update-statistics-2-SendThread(localhost:2181)] zookeeper.ClientCnxn: Opening socket connection to server localhost/127.0.0.1:2181. Will not attempt to authenticate using SASL (unknown error) > 2017-10-16 11:36:55,532 INFO [phoenix-update-statistics-2-SendThread(localhost:2181)] zookeeper.ClientCnxn: Socket connection established to localhost/127.0.0.1:2181, initiating session > 2017-10-16 11:36:55,532 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxnFactory: Accepted socket connection from /127.0.0.1:56483 > 2017-10-16 11:36:55,532 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.ZooKeeperServer: Client attempting to establish new session at /127.0.0.1:56483 > 2017-10-16 11:36:55,538 INFO [SyncThread:0] server.ZooKeeperServer: Established session 0x15f2678fade0018 with negotiated timeout 10000 for client /127.0.0.1:56483 > 2017-10-16 11:36:55,538 INFO [phoenix-update-statistics-2-SendThread(localhost:2181)] zookeeper.ClientCnxn: Session establishment complete on server localhost/127.0.0.1:2181, sessionid = 0x15f2678fade0018, negotiated timeout = 10000 > 2017-10-16 11:36:55,543 INFO [phoenix-update-statistics-2] client.ConnectionManager$HConnectionImplementation: Closing zookeeper sessionid=0x15f2678fade0018 > 2017-10-16 11:36:55,543 INFO [ProcessThread(sid:0 cport:-1):] server.PrepRequestProcessor: Processed session termination for sessionid: 0x15f2678fade0018 > 2017-10-16 11:36:55,549 INFO [phoenix-update-statistics-2] zookeeper.ZooKeeper: Session: 0x15f2678fade0018 closed > 2017-10-16 11:36:55,549 INFO [phoenix-update-statistics-2-EventThread] zookeeper.ClientCnxn: EventThread shut down > 2017-10-16 11:36:55,550 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxn: Closed socket connection for client /127.0.0.1:56483 which had sessionid 0x15f2678fade0018 > 2017-10-16 11:36:55,551 INFO [phoenix-update-statistics-2] zookeeper.RecoverableZooKeeper: Process identifier=hconnection-0x7082c0f1 connecting to ZooKeeper ensemble=localhost:2181 > 2017-10-16 11:36:55,551 INFO [phoenix-update-statistics-2] zookeeper.ZooKeeper: Initiating client connection, connectString=localhost:2181 sessionTimeout=10000 watcher=org.apache.hadoop.hbase.zookeeper.PendingWatcher@340d0b47 > 2017-10-16 11:36:55,552 INFO [phoenix-update-statistics-2-SendThread(localhost:2181)] zookeeper.ClientCnxn: Opening socket connection to server localhost/127.0.0.1:2181. Will not attempt to authenticate using SASL (unknown error) > 2017-10-16 11:36:55,552 INFO [phoenix-update-statistics-2-SendThread(localhost:2181)] zookeeper.ClientCnxn: Socket connection established to localhost/127.0.0.1:2181, initiating session > 2017-10-16 11:36:55,552 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxnFactory: Accepted socket connection from /127.0.0.1:56484 > 2017-10-16 11:36:55,553 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.ZooKeeperServer: Client attempting to establish new session at /127.0.0.1:56484 > 2017-10-16 11:36:55,558 INFO [SyncThread:0] server.ZooKeeperServer: Established session 0x15f2678fade0019 with negotiated timeout 10000 for client /127.0.0.1:56484 > 2017-10-16 11:36:55,558 INFO [phoenix-update-statistics-2-SendThread(localhost:2181)] zookeeper.ClientCnxn: Session establishment complete on server localhost/127.0.0.1:2181, sessionid = 0x15f2678fade0019, negotiated timeout = 10000 > 2017-10-16 11:36:56,242 INFO [phoenix-update-statistics-1] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS finished successfully for scanner: org.apache.hadoop.hbase.regionserver.HRegion$RegionScannerImpl@59f0254e. Number of rows scanned: 410342. Time: 3133 > 2017-10-16 11:36:56,242 INFO [phoenix-update-statistics-1] client.ConnectionManager$HConnectionImplementation: Closing zookeeper sessionid=0x15f2678fade0017 > 2017-10-16 11:36:56,243 INFO [ProcessThread(sid:0 cport:-1):] server.PrepRequestProcessor: Processed session termination for sessionid: 0x15f2678fade0017 > 2017-10-16 11:36:56,250 INFO [phoenix-update-statistics-1-EventThread] zookeeper.ClientCnxn: EventThread shut down > 2017-10-16 11:36:56,250 INFO [phoenix-update-statistics-1] zookeeper.ZooKeeper: Session: 0x15f2678fade0017 closed > 2017-10-16 11:36:56,251 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxn: Closed socket connection for client /127.0.0.1:56480 which had sessionid 0x15f2678fade0017 > 2017-10-16 11:36:56,251 INFO [phoenix-update-statistics-1] zookeeper.RecoverableZooKeeper: Process identifier=hconnection-0x129e3c87 connecting to ZooKeeper ensemble=localhost:2181 > 2017-10-16 11:36:56,251 INFO [phoenix-update-statistics-1] zookeeper.ZooKeeper: Initiating client connection, connectString=localhost:2181 sessionTimeout=10000 watcher=org.apache.hadoop.hbase.zookeeper.PendingWatcher@2144f869 > 2017-10-16 11:36:56,253 INFO [phoenix-update-statistics-1-SendThread(localhost:2181)] zookeeper.ClientCnxn: Opening socket connection to server localhost/127.0.0.1:2181. Will not attempt to authenticate using SASL (unknown error) > 2017-10-16 11:36:56,255 INFO [phoenix-update-statistics-1-SendThread(localhost:2181)] zookeeper.ClientCnxn: Socket connection established to localhost/127.0.0.1:2181, initiating session > 2017-10-16 11:36:56,255 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxnFactory: Accepted socket connection from /127.0.0.1:56485 > 2017-10-16 11:36:56,255 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.ZooKeeperServer: Client attempting to establish new session at /127.0.0.1:56485 > 2017-10-16 11:36:56,258 INFO [SyncThread:0] server.ZooKeeperServer: Established session 0x15f2678fade001a with negotiated timeout 10000 for client /127.0.0.1:56485 > 2017-10-16 11:36:56,258 INFO [phoenix-update-statistics-1-SendThread(localhost:2181)] zookeeper.ClientCnxn: Session establishment complete on server localhost/127.0.0.1:2181, sessionid = 0x15f2678fade001a, negotiated timeout = 10000 > 2017-10-16 11:36:56,265 INFO [phoenix-update-statistics-1] client.ConnectionManager$HConnectionImplementation: Closing zookeeper sessionid=0x15f2678fade001a > 2017-10-16 11:36:56,265 INFO [ProcessThread(sid:0 cport:-1):] server.PrepRequestProcessor: Processed session termination for sessionid: 0x15f2678fade001a > 2017-10-16 11:36:56,270 INFO [phoenix-update-statistics-1] zookeeper.ZooKeeper: Session: 0x15f2678fade001a closed > 2017-10-16 11:36:56,270 INFO [phoenix-update-statistics-1-EventThread] zookeeper.ClientCnxn: EventThread shut down > 2017-10-16 11:36:56,271 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxn: Closed socket connection for client /127.0.0.1:56485 which had sessionid 0x15f2678fade001a > 2017-10-16 11:36:56,272 INFO [phoenix-update-statistics-1] zookeeper.RecoverableZooKeeper: Process identifier=hconnection-0x17616591 connecting to ZooKeeper ensemble=localhost:2181 > 2017-10-16 11:36:56,272 INFO [phoenix-update-statistics-1] zookeeper.ZooKeeper: Initiating client connection, connectString=localhost:2181 sessionTimeout=10000 watcher=org.apache.hadoop.hbase.zookeeper.PendingWatcher@17d65b6f > 2017-10-16 11:36:56,273 INFO [phoenix-update-statistics-1-SendThread(localhost:2181)] zookeeper.ClientCnxn: Opening socket connection to server localhost/127.0.0.1:2181. Will not attempt to authenticate using SASL (unknown error) > 2017-10-16 11:36:56,274 INFO [phoenix-update-statistics-1-SendThread(localhost:2181)] zookeeper.ClientCnxn: Socket connection established to localhost/127.0.0.1:2181, initiating session > 2017-10-16 11:36:56,274 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxnFactory: Accepted socket connection from /127.0.0.1:56486 > 2017-10-16 11:36:56,274 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.ZooKeeperServer: Client attempting to establish new session at /127.0.0.1:56486 > 2017-10-16 11:36:56,280 INFO [SyncThread:0] server.ZooKeeperServer: Established session 0x15f2678fade001b with negotiated timeout 10000 for client /127.0.0.1:56486 > 2017-10-16 11:36:56,280 INFO [phoenix-update-statistics-1-SendThread(localhost:2181)] zookeeper.ClientCnxn: Session establishment complete on server localhost/127.0.0.1:2181, sessionid = 0x15f2678fade001b, negotiated timeout = 10000 > 2017-10-16 11:36:56,287 INFO [phoenix-update-statistics-1] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS finished successfully for scanner: org.apache.hadoop.hbase.regionserver.HRegion$RegionScannerImpl@6360cd2a. Number of rows scanned: 1. Time: 36 > 2017-10-16 11:36:56,288 INFO [phoenix-update-statistics-1] client.ConnectionManager$HConnectionImplementation: Closing zookeeper sessionid=0x15f2678fade001b > 2017-10-16 11:36:56,288 INFO [ProcessThread(sid:0 cport:-1):] server.PrepRequestProcessor: Processed session termination for sessionid: 0x15f2678fade001b > 2017-10-16 11:36:56,294 INFO [phoenix-update-statistics-1] zookeeper.ZooKeeper: Session: 0x15f2678fade001b closed > 2017-10-16 11:36:56,294 INFO [phoenix-update-statistics-1-EventThread] zookeeper.ClientCnxn: EventThread shut down > 2017-10-16 11:36:56,295 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxn: Closed socket connection for client /127.0.0.1:56486 which had sessionid 0x15f2678fade001b > 2017-10-16 11:36:57,662 INFO [phoenix-update-statistics-0] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS finished successfully for scanner: org.apache.hadoop.hbase.regionserver.HRegion$RegionScannerImpl@7309c930. Number of rows scanned: 871520. Time: 5218 > 2017-10-16 11:36:57,662 INFO [phoenix-update-statistics-0] client.ConnectionManager$HConnectionImplementation: Closing zookeeper sessionid=0x15f2678fade0013 > 2017-10-16 11:36:57,662 INFO [ProcessThread(sid:0 cport:-1):] server.PrepRequestProcessor: Processed session termination for sessionid: 0x15f2678fade0013 > 2017-10-16 11:36:57,669 INFO [phoenix-update-statistics-0] zookeeper.ZooKeeper: Session: 0x15f2678fade0013 closed > 2017-10-16 11:36:57,669 INFO [phoenix-update-statistics-0-EventThread] zookeeper.ClientCnxn: EventThread shut down > 2017-10-16 11:36:57,670 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxn: Closed socket connection for client /127.0.0.1:56477 which had sessionid 0x15f2678fade0013 > 2017-10-16 11:36:57,886 INFO [phoenix-update-statistics-2] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS finished successfully for scanner: org.apache.hadoop.hbase.regionserver.HRegion$RegionScannerImpl@af1f585. Number of rows scanned: 450992. Time: 2360 > 2017-10-16 11:36:57,886 INFO [phoenix-update-statistics-2] client.ConnectionManager$HConnectionImplementation: Closing zookeeper sessionid=0x15f2678fade0019 > 2017-10-16 11:36:57,886 INFO [ProcessThread(sid:0 cport:-1):] server.PrepRequestProcessor: Processed session termination for sessionid: 0x15f2678fade0019 > 2017-10-16 11:36:57,893 INFO [phoenix-update-statistics-2] zookeeper.ZooKeeper: Session: 0x15f2678fade0019 closed > 2017-10-16 11:36:57,893 INFO [phoenix-update-statistics-2-EventThread] zookeeper.ClientCnxn: EventThread shut down > 2017-10-16 11:36:57,894 INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181] server.NIOServerCnxn: Closed socket connection for client /127.0.0.1:56484 which had sessionid 0x15f2678fade0019 > 2017-10-16 11:36:58,584 INFO [phoenix-update-statistics-3] coprocessor.UngroupedAggregateRegionObserver: UPDATE STATISTICS finished successfully for scanner: org.apache.hadoop.hbase.regionserver.HRegion$RegionScannerImpl@b70e26e. Number of rows scanned: 1390358. Time: 9785 > {noformat} -- This message was sent by Atlassian JIRA (v6.4.14#64029)