[ https://issues.apache.org/jira/browse/PHOENIX-4289?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Samarth Jain updated PHOENIX-4289: ---------------------------------- Attachment: PHOENIX-4289.patch > 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 > > > 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)