Yep, I've restarted numerous times as I've been interating on this. I did it again just now to be sure, and got the same error.
One thing I'm curious about... since this is happening in an HBase RPC related class, I suspect it may have something to do with Kylin's ability to talk to HBase. Now building the cube seems to work, so at that level Kylin is talking to HBase OK, as far as I can tell, but I wonder if it uses a different mechanism at query time, as opposed to build time? Is Kylin connecting to Zookeeper and getting the HBase Master info from Zookeeper, when it runs a query? If so, are there any settings that might need to be twiddled, relating to how it finds Zookeeper, etc? Phil On Tue, May 3, 2016 at 2:38 PM, Jason Wang (WDG) <[email protected]> wrote: > Have you tried to restart your kylin server? This might be a cache problem. > > -----Original Message----- > From: Phillip Rhodes [mailto:[email protected]] > Sent: Tuesday, May 3, 2016 11:12 AM > To: [email protected] > Subject: Re: Kylin sample cube: Cube empty / timeout visiting cube, after > build > > Kylin 1.5.1, using HBase 0.9.8 with MapR 5.0.0 > > Here's a longer (and newer) snippet of the kylin.log showing the error I'm > getting. > > > Thanks, > > > Phil > > > ==========================[QUERY]=============================== > SQL: select part_dt, sum(price) as total_selled, count(distinct > seller_id) as sellers from kylin_sales group by part_dt order by part_dt > User: ADMIN > Success: false > Duration: 0.0 > Project: null > Realization Names: [] > Cuboid Ids: [] > Total scan count: 0 > Result row count: 0 > Accept Partial: true > Is Partial Result: false > Hit Exception Cache: false > Storage cache used: false > Message: Trying to normalized a project name which is null > ==========================[QUERY]=============================== > > 2016-05-03 04:34:16,822 ERROR [http-bio-7070-exec-2] > controller.BasicController:44 : > org.apache.kylin.rest.exception.InternalErrorException: Trying to normalized > a project name which is null at > org.apache.kylin.rest.controller.QueryController.doQueryWithCache(QueryController.java:224) > at > org.apache.kylin.rest.controller.QueryController.query(QueryController.java:94) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) > at > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:497) > at > org.springframework.web.method.support.InvocableHandlerMethod.invoke(InvocableHandlerMethod.java:213) > at > org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:126) > at > org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:96) > at > org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.invokeHandlerMethod(RequestMappingHandlerAdapter.java:617) > at > org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.handleInternal(RequestMappingHandlerAdapter.java:578) > at > org.springframework.web.servlet.mvc.method.AbstractHandlerMethodAdapter.handle(AbstractHandlerMethodAdapter.java:80) > at > org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:923) > at > org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:852) > at > org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:882) > at > org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:789) > at javax.servlet.http.HttpServlet.service(HttpServlet.java:646) > at javax.servlet.http.HttpServlet.service(HttpServlet.java:727) > at > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303) > at > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) > at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52) > at > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241) > at > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:330) > at > org.springframework.security.web.access.intercept.FilterSecurityInterceptor.invoke(FilterSecurityInterceptor.java:118) > at > org.springframework.security.web.access.intercept.FilterSecurityInterceptor.doFilter(FilterSecurityInterceptor.java:84) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.access.ExceptionTranslationFilter.doFilter(ExceptionTranslationFilter.java:113) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.session.SessionManagementFilter.doFilter(SessionManagementFilter.java:103) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.authentication.AnonymousAuthenticationFilter.doFilter(AnonymousAuthenticationFilter.java:113) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter.doFilter(SecurityContextHolderAwareRequestFilter.java:54) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.savedrequest.RequestCacheAwareFilter.doFilter(RequestCacheAwareFilter.java:45) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.authentication.www.BasicAuthenticationFilter.doFilter(BasicAuthenticationFilter.java:150) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.authentication.ui.DefaultLoginPageGeneratingFilter.doFilter(DefaultLoginPageGeneratingFilter.java:91) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.authentication.AbstractAuthenticationProcessingFilter.doFilter(AbstractAuthenticationProcessingFilter.java:183) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.authentication.logout.LogoutFilter.doFilter(LogoutFilter.java:105) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.context.SecurityContextPersistenceFilter.doFilter(SecurityContextPersistenceFilter.java:87) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.FilterChainProxy.doFilterInternal(FilterChainProxy.java:192) > at > org.springframework.security.web.FilterChainProxy.doFilter(FilterChainProxy.java:160) > at > org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:346) > at > org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:259) > at > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241) > at > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) > at com.thetransactioncompany.cors.CORSFilter.doFilter(CORSFilter.java:195) > at com.thetransactioncompany.cors.CORSFilter.doFilter(CORSFilter.java:266) > at > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241) > at > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) > at > org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220) > at > org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122) > at > org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:504) > at > org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:170) > at > org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:103) > at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:950) > at > org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116) > at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:421) > at > org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1074) > at > org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:611) > at > org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:314) > at > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) > at > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) > at > org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) > at java.lang.Thread.run(Thread.java:745) > 2016-05-03 04:34:22,018 DEBUG [http-bio-7070-exec-3] > service.CacheService:144 : Creating a new data source, OLAP data source > pointing to kylin_metadata@hbase > 2016-05-03 04:34:22,022 INFO [http-bio-7070-exec-3] > project.ProjectL2Cache:172 : Loading L2 project cache for LEARN_KYLIN > 2016-05-03 04:34:22,023 INFO [http-bio-7070-exec-3] > realization.RealizationRegistry:75 : Initializing RealizationRegistry with > metadata url kylin_metadata@hbase > 2016-05-03 04:34:22,541 INFO [http-bio-7070-exec-3] > reflections.Reflections:224 : Reflections took 503 ms to scan 18 urls, > producing 274 keys and 2131 values > 2016-05-03 04:34:22,549 INFO [http-bio-7070-exec-3] > invertedindex.IIManager:101 : Initializing IIManager with config > kylin_metadata@hbase > 2016-05-03 04:34:22,554 DEBUG [http-bio-7070-exec-3] > invertedindex.IIManager:226 : Loading II from folder > kylin_metadata(key='/invertedindex')@kylin_metadata@hbase > 2016-05-03 04:34:22,555 DEBUG [http-bio-7070-exec-3] > invertedindex.IIManager:232 : Loaded 0 II(s) > 2016-05-03 04:34:22,555 INFO [http-bio-7070-exec-3] > realization.RealizationRegistry:103 : RealizationRegistry is > {INVERTED_INDEX=org.apache.kylin.invertedindex.IIManager@79b1c3c, > CUBE=org.apache.kylin.cube.CubeManager@299efc5f, > HYBRID=org.apache.kylin.storage.hybrid.HybridManager@120c16bc} > 2016-05-03 04:34:22,559 INFO [http-bio-7070-exec-3] > schema.OLAPSchemaFactory:135 : Schema json:{ > "version": "1.0", > "defaultSchema": "DEFAULT", > "schemas": [ > { > "type": "custom", > "name": "DEFAULT", > "factory": "org.apache.kylin.query.schema.OLAPSchemaFactory", > "operand": { > "project": "LEARN_KYLIN" > }, > "functions": [ > { > name: 'MASSIN', > className: 'org.apache.kylin.query.udf.MassInUDF' > } > ] > } > ] > } > 2016-05-03 04:34:22,560 DEBUG [http-bio-7070-exec-3] > service.CacheService:149 : The new temp olap json is :{ > "version": "1.0", > "defaultSchema": "DEFAULT", > "schemas": [ > { > "type": "custom", > "name": "DEFAULT", > "factory": "org.apache.kylin.query.schema.OLAPSchemaFactory", > "operand": { > "project": "LEARN_KYLIN" > }, > "functions": [ > { > name: 'MASSIN', > className: 'org.apache.kylin.query.udf.MassInUDF' > } > ] > } > ] > } > > 2016-05-03 04:34:22,998 DEBUG [http-bio-7070-exec-3] > service.QueryService:289 : getting table metas > 2016-05-03 04:34:23,050 INFO [http-bio-7070-exec-3] > project.ProjectL2Cache:172 : Loading L2 project cache for learn_kylin > 2016-05-03 04:34:23,051 DEBUG [http-bio-7070-exec-3] > service.QueryService:307 : getting column metas > 2016-05-03 04:34:23,090 DEBUG [http-bio-7070-exec-3] > service.QueryService:321 : done column metas > 2016-05-03 04:34:23,845 DEBUG [http-bio-7070-exec-10] > controller.UserController:64 : authentication.getPrincipal() is > org.springframework.security.core.userdetails.User@3b40b2f: Username: > ADMIN; Password: [PROTECTED]; Enabled: true; AccountNonExpired: true; > credentialsNonExpired: true; AccountNonLocked: true; Granted > Authorities: ROLE_ADMIN,ROLE_ANALYST,ROLE_MODELER > 2016-05-03 04:34:23,965 DEBUG [http-bio-7070-exec-5] > hbase.HBaseResourceStore:260 : Update row /project/learn_kylin.json from > oldTs: 1460427992000, to newTs: 1462217663955, operation result: > true > 2016-05-03 04:34:23,966 INFO [pool-3-thread-1] > restclient.Broadcaster:101 : new broadcast event:BroadcastEvent{type=project, > name=learn_kylin, action=update} > 2016-05-03 04:34:23,971 INFO [http-bio-7070-exec-9] > controller.CacheController:64 : wipe cache type: PROJECT event:UPDATE > name:learn_kylin > 2016-05-03 04:34:23,971 INFO [http-bio-7070-exec-9] > service.CacheService:171 : rebuild cache type: PROJECT name:learn_kylin > 2016-05-03 04:34:23,973 INFO [http-bio-7070-exec-9] > service.CacheService:124 : removeOLAPDataSource is called for project > learn_kylin > 2016-05-03 04:34:24,099 INFO [http-bio-7070-exec-7] > project.ProjectL2Cache:172 : Loading L2 project cache for learn_kylin > 2016-05-03 04:34:24,102 INFO [http-bio-7070-exec-7] > controller.TableController:89 : Return all table metadata in 3 seconds > 2016-05-03 04:34:24,852 DEBUG [http-bio-7070-exec-2] > controller.UserController:64 : authentication.getPrincipal() is > org.springframework.security.core.userdetails.User@3b40b2f: Username: > ADMIN; Password: [PROTECTED]; Enabled: true; AccountNonExpired: true; > credentialsNonExpired: true; AccountNonLocked: true; Granted > Authorities: ROLE_ADMIN,ROLE_ANALYST,ROLE_MODELER > 2016-05-03 04:34:25,034 DEBUG [http-bio-7070-exec-8] > service.CacheService:144 : Creating a new data source, OLAP data source > pointing to kylin_metadata@hbase > 2016-05-03 04:34:25,034 INFO [http-bio-7070-exec-8] > project.ProjectL2Cache:172 : Loading L2 project cache for LEARN_KYLIN > 2016-05-03 04:34:25,035 INFO [http-bio-7070-exec-8] > schema.OLAPSchemaFactory:135 : Schema json:{ > "version": "1.0", > "defaultSchema": "DEFAULT", > "schemas": [ > { > "type": "custom", > "name": "DEFAULT", > "factory": "org.apache.kylin.query.schema.OLAPSchemaFactory", > "operand": { > "project": "LEARN_KYLIN" > }, > "functions": [ > { > name: 'MASSIN', > className: 'org.apache.kylin.query.udf.MassInUDF' > } > ] > } > ] > } > 2016-05-03 04:34:25,036 DEBUG [http-bio-7070-exec-8] > service.CacheService:149 : The new temp olap json is :{ > "version": "1.0", > "defaultSchema": "DEFAULT", > "schemas": [ > { > "type": "custom", > "name": "DEFAULT", > "factory": "org.apache.kylin.query.schema.OLAPSchemaFactory", > "operand": { > "project": "LEARN_KYLIN" > }, > "functions": [ > { > name: 'MASSIN', > className: 'org.apache.kylin.query.udf.MassInUDF' > } > ] > } > ] > } > > 2016-05-03 04:34:25,055 DEBUG [http-bio-7070-exec-8] > service.QueryService:289 : getting table metas > 2016-05-03 04:34:25,056 DEBUG [http-bio-7070-exec-8] > service.QueryService:307 : getting column metas > 2016-05-03 04:34:25,068 DEBUG [http-bio-7070-exec-8] > service.QueryService:321 : done column metas > 2016-05-03 04:34:30,695 INFO [http-bio-7070-exec-3] > controller.QueryController:174 : Using project: learn_kylin > 2016-05-03 04:34:30,696 INFO [http-bio-7070-exec-3] > controller.QueryController:175 : The original query: select part_dt, > sum(price) as total_selled, count(distinct seller_id) as sellers from > kylin_sales group by part_dt order by part_dt > 2016-05-03 04:34:30,697 INFO [http-bio-7070-exec-3] > service.QueryService:265 : The corrected query: select part_dt, > sum(price) as total_selled, count(distinct seller_id) as sellers from > kylin_sales group by part_dt order by part_dt LIMIT 50000 > 2016-05-03 04:34:31,218 INFO [http-bio-7070-exec-3] > routing.QueryRouter:48 : The project manager's reference is > org.apache.kylin.metadata.project.ProjectManager@5c4a45fc > 2016-05-03 04:34:31,219 INFO [http-bio-7070-exec-3] > routing.QueryRouter:60 : Find candidates by table DEFAULT.KYLIN_SALES and > project=LEARN_KYLIN : > org.apache.kylin.query.routing.Candidate@408561ee > 2016-05-03 04:34:31,222 INFO [http-bio-7070-exec-3] > routing.QueryRouter:49 : Applying rule: class > org.apache.kylin.query.routing.rules.RemoveUncapableRealizationsRule, > realizations before: [kylin_sales_cube(CUBE)], realizations after: > [kylin_sales_cube(CUBE)] > 2016-05-03 04:34:31,222 INFO [http-bio-7070-exec-3] > routing.QueryRouter:49 : Applying rule: class > org.apache.kylin.query.routing.rules.RealizationSortRule, realizations > before: [kylin_sales_cube(CUBE)], realizations after: > [kylin_sales_cube(CUBE)] > 2016-05-03 04:34:31,222 INFO [http-bio-7070-exec-3] > routing.QueryRouter:72 : The realizations remaining: > [kylin_sales_cube(CUBE)] And the final chosen one is the first one > 2016-05-03 04:34:31,513 DEBUG [http-bio-7070-exec-3] > enumerator.OLAPEnumerator:107 : query storage... > 2016-05-03 04:34:31,522 INFO [http-bio-7070-exec-3] > v2.CubeStorageQuery:251 : exactAggregation is true > 2016-05-03 04:34:31,522 INFO [http-bio-7070-exec-3] > v2.CubeStorageQuery:357 : Memory budget is set to: 3109290 > 2016-05-03 04:34:31,550 DEBUG [http-bio-7070-exec-3] > v2.CubeHBaseEndpointRPC:256 : New scanner for current segment > kylin_sales_cube[20120101000000_20160501000000] will use > SCAN_FILTER_AGGR_CHECKMEM as endpoint's behavior > 2016-05-03 04:34:31,562 DEBUG [http-bio-7070-exec-3] > v2.CubeHBaseEndpointRPC:291 : Serialized scanRequestBytes 321 bytes, > rawScanBytesString 46 bytes > 2016-05-03 04:34:31,562 INFO [http-bio-7070-exec-3] > v2.CubeHBaseEndpointRPC:294 : The scan(s) info for current segment is as > below, shard part of start/end key is set to 0 > 2016-05-03 04:34:31,562 INFO [http-bio-7070-exec-3] > v2.CubeHBaseRPC:309 : Visiting hbase table KYLIN_JFVKIFVB8S: cuboid exact > match, from 64 to 64 Start: > \x00\x00\x00\x00\x00\x00\x00\x00\x00\x40\x00\x00\x00 > (\x00\x00\x00\x00\x00\x00\x00\x00\x00@\x00\x00\x00) Stop: > \x00\x00\x00\x00\x00\x00\x00\x00\x00\x40\xFF\xFF\xFF\x00 > (\x00\x00\x00\x00\x00\x00\x00\x00\x00@\xFF\xFF\xFF\x00), No Fuzzy Key > 2016-05-03 04:34:31,563 DEBUG [http-bio-7070-exec-3] > v2.CubeHBaseEndpointRPC:300 : Submitting rpc to 1 shards starting from shard > 0, scan requests count 1 > 2016-05-03 04:34:31,582 INFO [http-bio-7070-exec-3] > v2.CubeHBaseEndpointRPC:125 : Timeout for ExpectedSizeIterator is > 60000 > 2016-05-03 04:34:31,588 DEBUG [http-bio-7070-exec-3] > enumerator.OLAPEnumerator:127 : return TupleIterator... > 2016-05-03 04:34:31,592 ERROR [pool-9-thread-1] > v2.CubeHBaseEndpointRPC:98 : Execution exception when running task in > pool-9-thread-1 > 2016-05-03 04:34:31,592 ERROR [pool-9-thread-1] > v2.CubeHBaseEndpointRPC:108 : Caught exception in thread > pool-9-thread-1: > java.lang.NullPointerException > at > org.apache.kylin.storage.hbase.cube.v2.CubeHBaseEndpointRPC$1.run(CubeHBaseEndpointRPC.java:326) > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) > at java.util.concurrent.FutureTask.run(FutureTask.java:266) > at > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) > at > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) > at java.lang.Thread.run(Thread.java:745) > 2016-05-03 04:35:05,120 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:35:31,589 ERROR [http-bio-7070-exec-3] > controller.QueryController:209 : Exception when execute sql > java.sql.SQLException: Error while executing SQL "select part_dt, > sum(price) as total_selled, count(distinct seller_id) as sellers from > kylin_sales group by part_dt order by part_dt LIMIT 50000": Timeout visiting > cube! > at org.apache.calcite.avatica.Helper.createException(Helper.java:56) > at org.apache.calcite.avatica.Helper.createException(Helper.java:41) > at > org.apache.calcite.avatica.AvaticaStatement.executeInternal(AvaticaStatement.java:143) > at > org.apache.calcite.avatica.AvaticaStatement.executeQuery(AvaticaStatement.java:186) > at org.apache.kylin.rest.service.QueryService.execute(QueryService.java:356) > at > org.apache.kylin.rest.service.QueryService.queryWithSqlMassage(QueryService.java:272) > at org.apache.kylin.rest.service.QueryService.query(QueryService.java:118) > at > org.apache.kylin.rest.service.QueryService$$FastClassByCGLIB$$4957273f.invoke(<generated>) > at net.sf.cglib.proxy.MethodProxy.invoke(MethodProxy.java:204) > at > org.springframework.aop.framework.Cglib2AopProxy$DynamicAdvisedInterceptor.intercept(Cglib2AopProxy.java:618) > at > org.apache.kylin.rest.service.QueryService$$EnhancerByCGLIB$$c9bef1fc.query(<generated>) > at > org.apache.kylin.rest.controller.QueryController.doQueryWithCache(QueryController.java:192) > at > org.apache.kylin.rest.controller.QueryController.query(QueryController.java:94) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) > at > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:497) > at > org.springframework.web.method.support.InvocableHandlerMethod.invoke(InvocableHandlerMethod.java:213) > at > org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:126) > at > org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:96) > at > org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.invokeHandlerMethod(RequestMappingHandlerAdapter.java:617) > at > org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.handleInternal(RequestMappingHandlerAdapter.java:578) > at > org.springframework.web.servlet.mvc.method.AbstractHandlerMethodAdapter.handle(AbstractHandlerMethodAdapter.java:80) > at > org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:923) > at > org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:852) > at > org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:882) > at > org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:789) > at javax.servlet.http.HttpServlet.service(HttpServlet.java:646) > at javax.servlet.http.HttpServlet.service(HttpServlet.java:727) > at > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303) > at > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) > at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52) > at > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241) > at > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:330) > at > org.springframework.security.web.access.intercept.FilterSecurityInterceptor.invoke(FilterSecurityInterceptor.java:118) > at > org.springframework.security.web.access.intercept.FilterSecurityInterceptor.doFilter(FilterSecurityInterceptor.java:84) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.access.ExceptionTranslationFilter.doFilter(ExceptionTranslationFilter.java:113) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.session.SessionManagementFilter.doFilter(SessionManagementFilter.java:103) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.authentication.AnonymousAuthenticationFilter.doFilter(AnonymousAuthenticationFilter.java:113) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter.doFilter(SecurityContextHolderAwareRequestFilter.java:54) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.savedrequest.RequestCacheAwareFilter.doFilter(RequestCacheAwareFilter.java:45) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.authentication.www.BasicAuthenticationFilter.doFilter(BasicAuthenticationFilter.java:150) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.authentication.ui.DefaultLoginPageGeneratingFilter.doFilter(DefaultLoginPageGeneratingFilter.java:91) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.authentication.AbstractAuthenticationProcessingFilter.doFilter(AbstractAuthenticationProcessingFilter.java:183) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.authentication.logout.LogoutFilter.doFilter(LogoutFilter.java:105) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.context.SecurityContextPersistenceFilter.doFilter(SecurityContextPersistenceFilter.java:87) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.FilterChainProxy.doFilterInternal(FilterChainProxy.java:192) > at > org.springframework.security.web.FilterChainProxy.doFilter(FilterChainProxy.java:160) > at > org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:346) > at > org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:259) > at > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241) > at > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) > at com.thetransactioncompany.cors.CORSFilter.doFilter(CORSFilter.java:195) > at com.thetransactioncompany.cors.CORSFilter.doFilter(CORSFilter.java:266) > at > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241) > at > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) > at > org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220) > at > org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122) > at > org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:504) > at > org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:170) > at > org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:103) > at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:950) > at > org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116) > at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:421) > at > org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1074) > at > org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:611) > at > org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:316) > at > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) > at > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) > at > org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) > at java.lang.Thread.run(Thread.java:745) > Caused by: java.lang.RuntimeException: Timeout visiting cube! > at > org.apache.kylin.storage.hbase.cube.v2.CubeHBaseEndpointRPC$ExpectedSizeIterator.next(CubeHBaseEndpointRPC.java:142) > at > org.apache.kylin.storage.hbase.cube.v2.CubeHBaseEndpointRPC$ExpectedSizeIterator.next(CubeHBaseEndpointRPC.java:113) > at > com.google.common.collect.TransformedIterator.next(TransformedIterator.java:48) > at com.google.common.collect.Iterators$6.hasNext(Iterators.java:583) > at > org.apache.kylin.storage.hbase.cube.v2.SequentialCubeTupleIterator.hasNext(SequentialCubeTupleIterator.java:96) > at > org.apache.kylin.query.enumerator.OLAPEnumerator.moveNext(OLAPEnumerator.java:74) > at Baz$1$1.moveNext(Unknown Source) > at > org.apache.calcite.linq4j.EnumerableDefaults.groupBy_(EnumerableDefaults.java:819) > at > org.apache.calcite.linq4j.EnumerableDefaults.groupBy(EnumerableDefaults.java:754) > at > org.apache.calcite.linq4j.DefaultEnumerable.groupBy(DefaultEnumerable.java:302) > at Baz.bind(Unknown Source) > at > org.apache.calcite.jdbc.CalcitePrepare$CalciteSignature.enumerable(CalcitePrepare.java:326) > at > org.apache.calcite.jdbc.CalciteConnectionImpl.enumerable(CalciteConnectionImpl.java:281) > at > org.apache.calcite.jdbc.CalciteMetaImpl._createIterable(CalciteMetaImpl.java:545) > at > org.apache.calcite.jdbc.CalciteMetaImpl.createIterable(CalciteMetaImpl.java:536) > at > org.apache.calcite.avatica.AvaticaResultSet.execute(AvaticaResultSet.java:187) > at org.apache.calcite.jdbc.CalciteResultSet.execute(CalciteResultSet.java:65) > at org.apache.calcite.jdbc.CalciteResultSet.execute(CalciteResultSet.java:44) > at > org.apache.calcite.avatica.AvaticaConnection$1.execute(AvaticaConnection.java:566) > at > org.apache.calcite.jdbc.CalciteMetaImpl.prepareAndExecute(CalciteMetaImpl.java:578) > at > org.apache.calcite.avatica.AvaticaConnection.prepareAndExecuteInternal(AvaticaConnection.java:571) > at > org.apache.calcite.avatica.AvaticaStatement.executeInternal(AvaticaStatement.java:135) > ... 80 more > 2016-05-03 04:35:31,591 INFO [http-bio-7070-exec-3] service.QueryService:249 > : > ==========================[QUERY]=============================== > SQL: select part_dt, sum(price) as total_selled, count(distinct > seller_id) as sellers from kylin_sales group by part_dt order by part_dt > User: ADMIN > Success: false > Duration: 0.0 > Project: learn_kylin > Realization Names: [kylin_sales_cube] > Cuboid Ids: [64] > Total scan count: 0 > Result row count: 0 > Accept Partial: true > Is Partial Result: false > Hit Exception Cache: false > Storage cache used: false > Message: Error while executing SQL "select part_dt, sum(price) as > total_selled, count(distinct seller_id) as sellers from kylin_sales group by > part_dt order by part_dt LIMIT 50000": Timeout visiting cube! > ==========================[QUERY]=============================== > > 2016-05-03 04:35:31,592 ERROR [http-bio-7070-exec-3] > controller.BasicController:44 : > org.apache.kylin.rest.exception.InternalErrorException: Error while executing > SQL "select part_dt, sum(price) as total_selled, count(distinct seller_id) as > sellers from kylin_sales group by part_dt order by part_dt LIMIT 50000": > Timeout visiting cube! > at > org.apache.kylin.rest.controller.QueryController.doQueryWithCache(QueryController.java:224) > at > org.apache.kylin.rest.controller.QueryController.query(QueryController.java:94) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) > at > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:497) > at > org.springframework.web.method.support.InvocableHandlerMethod.invoke(InvocableHandlerMethod.java:213) > at > org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:126) > at > org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:96) > at > org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.invokeHandlerMethod(RequestMappingHandlerAdapter.java:617) > at > org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.handleInternal(RequestMappingHandlerAdapter.java:578) > at > org.springframework.web.servlet.mvc.method.AbstractHandlerMethodAdapter.handle(AbstractHandlerMethodAdapter.java:80) > at > org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:923) > at > org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:852) > at > org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:882) > at > org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:789) > at javax.servlet.http.HttpServlet.service(HttpServlet.java:646) > at javax.servlet.http.HttpServlet.service(HttpServlet.java:727) > at > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303) > at > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) > at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52) > at > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241) > at > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:330) > at > org.springframework.security.web.access.intercept.FilterSecurityInterceptor.invoke(FilterSecurityInterceptor.java:118) > at > org.springframework.security.web.access.intercept.FilterSecurityInterceptor.doFilter(FilterSecurityInterceptor.java:84) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.access.ExceptionTranslationFilter.doFilter(ExceptionTranslationFilter.java:113) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.session.SessionManagementFilter.doFilter(SessionManagementFilter.java:103) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.authentication.AnonymousAuthenticationFilter.doFilter(AnonymousAuthenticationFilter.java:113) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter.doFilter(SecurityContextHolderAwareRequestFilter.java:54) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.savedrequest.RequestCacheAwareFilter.doFilter(RequestCacheAwareFilter.java:45) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.authentication.www.BasicAuthenticationFilter.doFilter(BasicAuthenticationFilter.java:150) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.authentication.ui.DefaultLoginPageGeneratingFilter.doFilter(DefaultLoginPageGeneratingFilter.java:91) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.authentication.AbstractAuthenticationProcessingFilter.doFilter(AbstractAuthenticationProcessingFilter.java:183) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.authentication.logout.LogoutFilter.doFilter(LogoutFilter.java:105) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.context.SecurityContextPersistenceFilter.doFilter(SecurityContextPersistenceFilter.java:87) > at > org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342) > at > org.springframework.security.web.FilterChainProxy.doFilterInternal(FilterChainProxy.java:192) > at > org.springframework.security.web.FilterChainProxy.doFilter(FilterChainProxy.java:160) > at > org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:346) > at > org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:259) > at > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241) > at > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) > at com.thetransactioncompany.cors.CORSFilter.doFilter(CORSFilter.java:195) > at com.thetransactioncompany.cors.CORSFilter.doFilter(CORSFilter.java:266) > at > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241) > at > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) > at > org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220) > at > org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122) > at > org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:504) > at > org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:170) > at > org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:103) > at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:950) > at > org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116) > at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:421) > at > org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1074) > at > org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:611) > at > org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:316) > at > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) > at > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) > at > org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) > at java.lang.Thread.run(Thread.java:745) > 2016-05-03 04:36:05,120 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:37:05,120 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:38:05,120 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:39:05,119 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:40:05,119 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:41:05,120 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:42:05,119 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:43:05,119 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:44:05,119 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:45:05,120 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:46:05,120 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:47:05,120 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:48:05,119 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:49:05,119 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:50:05,120 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:51:05,120 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:52:05,119 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:53:05,120 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:56:02,197 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:56:02,206 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:56:05,121 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:57:05,121 INFO [pool-1-thread-1] > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running, 0 > ready, 2 others > 2016-05-03 04:57:22,712 DEBUG [Thread-8] > threadpool.DefaultScheduler:182 : Closing zk connection > 2016-05-03 04:58:54,467 INFO [localhost-startStop-1] > core.SpringSecurityCoreVersion:33 : You are running with Spring Security Core > 3.1.2.RELEASE > 2016-05-03 04:58:54,471 INFO [localhost-startStop-1] > config.SecurityNamespaceHandler:59 : Spring Security 'config' module version > is 3.1.2.RELEASE > 2016-05-03 04:58:54,517 INFO [localhost-startStop-1] > method.GlobalMethodSecurityBeanDefinitionParser:141 : Using bean > 'expressionHandler' as method ExpressionHandler implementation > 2016-05-03 04:58:54,581 INFO [localhost-startStop-1] > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access > control expression attribute 'permitAll' for > /api/user/authentication*/** > 2016-05-03 04:58:54,582 INFO [localhost-startStop-1] > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access > control expression attribute 'isAuthenticated()' for > /api/query*/** > 2016-05-03 04:58:54,582 INFO [localhost-startStop-1] > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access > control expression attribute 'isAuthenticated()' for > /api/metadata*/** > 2016-05-03 04:58:54,583 INFO [localhost-startStop-1] > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access > control expression attribute 'permitAll' for /api/**/metrics > 2016-05-03 04:58:54,583 INFO [localhost-startStop-1] > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access > control expression attribute 'permitAll' for /api/cache*/** > 2016-05-03 04:58:54,583 INFO [localhost-startStop-1] > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access > control expression attribute 'hasAnyRole('ROLE_ANALYST')' for > /api/cubes/src/tables > 2016-05-03 04:58:54,584 INFO [localhost-startStop-1] > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access > control expression attribute 'isAuthenticated()' for > /api/cubes*/** > 2016-05-03 04:58:54,584 INFO [localhost-startStop-1] > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access > control expression attribute 'isAuthenticated()' for > /api/models*/** > 2016-05-03 04:58:54,584 INFO [localhost-startStop-1] > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access > control expression attribute 'isAuthenticated()' for > /api/streaming*/** > 2016-05-03 04:58:54,585 INFO [localhost-startStop-1] > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access > control expression attribute 'isAuthenticated()' for > /api/job*/** > 2016-05-03 04:58:54,585 INFO [localhost-startStop-1] > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access > control expression attribute 'permitAll' for /api/admin/config > 2016-05-03 04:58:54,585 INFO [localhost-startStop-1] > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access > control expression attribute 'permitAll' for /api/projects > 2016-05-03 04:58:54,585 INFO [localhost-startStop-1] > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access > control expression attribute 'hasRole('ROLE_ADMIN')' for > /api/admin*/** > 2016-05-03 04:58:54,587 INFO [localhost-startStop-1] > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access > control expression attribute 'isAuthenticated()' for /api/** > 2016-05-03 04:58:54,606 INFO [localhost-startStop-1] > http.AuthenticationConfigBuilder:465 : No login page configured. The default > internal one will be used. Use the 'login-page' attribute to set the URL of > the login page. > > On Tue, May 3, 2016 at 5:05 AM, Li Yang <[email protected]> wrote: >> What's Kylin version? And more kylin.log is needed. Those lines before >> the stack trace. >> >> On Sat, Apr 30, 2016 at 2:54 AM, Phillip Rhodes >> <[email protected]> >> wrote: >>> >>> Kylin gang: >>> >>> I've installed Kylin with MapR distribution, and things seem to work >>> fine up to a point. I ran the sample.sh script and I now have the >>> "learning_kylin" project, and I can run "build" on the cube and the >>> job reports success at 100% complete. >>> >>> But.. after doing this, the display always shows my cube as being 0.0 >>> bytes, and if I run the sample query from the documentation, I get a >>> "timeout visiting cube" error. >>> >>> The other weird thing I've noticed, is that the "build" dialog is >>> showing a start date of today's date, and I can't edit it. Not sure >>> if that has anything to do with the weirdness I'm seeing or not, but >>> can anyone tell me how Kylin determines what value to put there? >>> >>> >>> Anyway, any thoughts on how to debug this are greatly appreciated. >>> I've examined the logs, but don't see anything that jumps out to me >>> as terribly useful. Here's what I find: >>> >>> ==========================[QUERY]=============================== >>> SQL: select part_dt, sum(price) as total_selled, count(distinct >>> seller_id) as sellers from kylin_sales group by part_dt order by >>> part_dt >>> User: ADMIN >>> Success: false >>> Duration: 0.0 >>> Project: learn_kylin >>> Realization Names: [kylin_sales_cube] Cuboid Ids: [64] Total scan >>> count: 0 Result row count: 0 Accept Partial: true Is Partial Result: >>> false Hit Exception Cache: false Storage cache used: false >>> Message: Error while executing SQL "select part_dt, sum(price) as >>> total_selled, count(distinct seller_id) as sellers from kylin_sales >>> group by part_dt order by part_dt LIMIT 50000": Timeout visiting >>> cube! >>> ==========================[QUERY]=============================== >>> >>> 2016-04-30 03:45:32,310 ERROR [http-bio-7070-exec-9] >>> controller.BasicController:44 : >>> org.apache.kylin.rest.exception.InternalErrorException: Error while >>> executing SQL "select part_dt, sum(price) as total_selled, >>> count(distinct seller_id) as sellers from kylin_sales group by >>> part_dt order by part_dt LIMIT 50000": Timeout visiting cube! >>> at >>> org.apache.kylin.rest.controller.QueryController.doQueryWithCache(QueryController.java:224) >>> at >>> org.apache.kylin.rest.controller.QueryController.query(QueryController.java:94) >>> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) >>> at >>> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) >>> at >>> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) >>> at java.lang.reflect.Method.invoke(Method.java:497) >>> at >>> org.springframework.web.method.support.InvocableHandlerMethod.invoke(InvocableHandlerMethod.java:213) >>> at >>> org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:126) >>> at >>> org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:96) >>> at >>> org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.invokeHandlerMethod(RequestMappingHandlerAdapter.java:617) >>> at >>> org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.handleInternal(RequestMappingHandlerAdapter.java:578) >>> at >>> org.springframework.web.servlet.mvc.method.AbstractHandlerMethodAdapter.handle(AbstractHandlerMethodAdapter.java:80) >>> at >>> org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:923) >>> at >>> org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:852) >>> at >>> org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:882) >>> at >>> org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServ >>> let.java:789) >>> >>> >>> >>> Thanks, >>> >>> >>> Phil >> >> >
