phoenix-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Hadoop QA (JIRA)" <j...@apache.org>
Subject [jira] [Commented] (PHOENIX-4289) UPDATE STATISTICS command does not collect stats for local indexes
Date Sun, 29 Oct 2017 07:48:02 GMT

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

Hadoop QA commented on PHOENIX-4289:
------------------------------------

{color:red}-1 overall{color}.  Here are the results of testing the latest attachment 
  http://issues.apache.org/jira/secure/attachment/12894618/PHOENIX-4289_v3.patch
  against master branch at commit 82a4dd8f78b38c0da6b019ccdead6879b87c6f26.
  ATTACHMENT ID: 12894618

    {color:green}+1 @author{color}.  The patch does not contain any @author tags.

    {color:red}-1 tests included{color}.  The patch doesn't appear to include any new or modified tests.
                        Please justify why no new tests are needed for this patch.
                        Also please list what manual steps were performed to verify this patch.

    {color:green}+1 javac{color}.  The applied patch does not increase the total number of javac compiler warnings.

    {color:green}+1 release audit{color}.  The applied patch does not increase the total number of release audit warnings.

    {color:red}-1 lineLengths{color}.  The patch introduces the following lines longer than 100:
    +            rowCount += updateStatisticsInternal(table.getPhysicalName(), table, updateStatisticsStmt.getProps(), true);
+                    rowCount += updateStatisticsInternal(table.getPhysicalName(), index, updateStatisticsStmt.getProps(), true);
+            List<byte[]> localCFs = MetaDataUtil.getLocalIndexColumnFamilies(connection, physicalName.getBytes());
+                 * stats can run for local indexes. Consider the case when the user calls UPDATE STATS TABLE
+                 * followed by UPDATE STATS TABLE INDEX. When the second statement is being executed,
+                 * this causes us to skip the check and execute stats collection possibly a bit too frequently.
+                rowCount += updateStatisticsInternal(physicalName, table, updateStatisticsStmt.getProps(), localCFs, false);
+                    final PName viewIndexPhysicalTableName = PNameFactory.newName(MetaDataUtil.getViewIndexPhysicalName(table.getPhysicalName().getBytes()));
+                    rowCount += updateStatisticsInternal(viewIndexPhysicalTableName, indexLogicalTable, updateStatisticsStmt.getProps(), true);
+    private long updateStatisticsInternal(PName physicalName, PTable logicalTable, Map<String, Object> statsProps, boolean checkLastStatsUpdateTime) throws SQLException {

     {color:red}-1 core tests{color}.  The patch failed these unit tests:
     ./phoenix-core/target/failsafe-reports/TEST-org.apache.phoenix.rpc.PhoenixServerRpcIT
./phoenix-core/target/failsafe-reports/TEST-org.apache.phoenix.end2end.ExplainPlanWithStatsEnabledIT

Test results: https://builds.apache.org/job/PreCommit-PHOENIX-Build/1586//testReport/
Console output: https://builds.apache.org/job/PreCommit-PHOENIX-Build/1586//console

This message is automatically generated.

> 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
>         Attachments: PHOENIX-4289.patch, PHOENIX-4289_v2.patch, PHOENIX-4289_v3.patch
>
>
> 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