[
https://issues.apache.org/jira/browse/PHOENIX-4289?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=16213608#comment-16213608
]
Samarth Jain commented on PHOENIX-4289:
---------------------------------------
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)
