phoenix-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Samarth Jain (JIRA)" <j...@apache.org>
Subject [jira] [Comment Edited] (PHOENIX-4289) UPDATE STATISTICS command does not collect stats for local indexes
Date Sat, 21 Oct 2017 00:59:00 GMT

    [ https://issues.apache.org/jira/browse/PHOENIX-4289?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=16213608#comment-16213608 ] 

Samarth Jain edited comment on PHOENIX-4289 at 10/21/17 12:58 AM:
------------------------------------------------------------------

I think I see what is going on. When a table has an index, we run update stats twice - once for the data table and once for the index table. We control update stats being called too many times in a short duration by using the configurable setting phoenix.stats.minUpdateFrequency. The check for when update stats was last run uses the physical_name as the filter. 
{code}
String query = "SELECT CURRENT_DATE()," + LAST_STATS_UPDATE_TIME + " FROM " + PhoenixDatabaseMetaData.SYSTEM_STATS_NAME
                + " WHERE " + PHYSICAL_NAME + "='" + physicalName.getString() + "' AND " + COLUMN_FAMILY
                + " IS NULL AND " + LAST_STATS_UPDATE_TIME + " IS NOT NULL";
{code}

For local indexes, the physical_name is same for both data table and index table. As a result the second update stats ends up not collecting any stats for the index table. The default value of this config is set to 0 in our tests. So an update statistics statement was collecting stats for both index and data tables. After setting QueryServicesTestImpl.DEFAULT_MIN_STATS_UPDATE_FREQ_MS to a large value, I am seeing now that the estimates are being returned as null.


was (Author: samarthjain):
I think I see what is going on. When a table has an index, we run update stats twice - once for the data table and once for the index table. We control update stats being called too many times in a short duration by using the configurable setting phoenix.stats.minUpdateFrequency. The check for when update stats was last run uses the physical_table_name as the filter. For local indexes, the physical_table_name is same for both data table and index table. As a result the second update stats ends up not collecting any stats for the index table. The default value of this config is set to 0 in our tests. So the tests weren't able to catch this issue. After setting QueryServicesTestImpl.DEFAULT_MIN_STATS_UPDATE_FREQ_MS to a large value, I am seeing now that the estimates are being returned as null.

> 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)

Mime
View raw message