You are viewing a plain text version of this content. The canonical link for it is here.
Posted to user@kylin.apache.org by Phillip Rhodes <pr...@mammothdata.com> on 2016/04/29 20:54:25 UTC

Kylin sample cube: Cube empty / timeout visiting cube, after build

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(FrameworkServlet.java:789)



Thanks,


Phil

Re: Kylin sample cube: Cube empty / timeout visiting cube, after build

Posted by Li Yang <li...@apache.org>.
Btw, Kylin has been connecting to HBase well, because Kylin stores its
metadata also in HBase and that part is working.

On Sun, May 8, 2016 at 7:12 PM, Li Yang <li...@apache.org> wrote:

> > java.lang.NullPointerException
> > at
> org.apache.kylin.storage.hbase.cube.v2.CubeHBaseEndpointRPC$1.run(CubeHBaseEndpointRPC.java:326)
>
> This is the cause of query exception. It is further caused by
> table.coprocessorService() returning a null. So it seems something wrong
> with your HTable.
>
> > the display always shows my cube as being 0.0 bytes
>
> Plus this evidence... Suggest check the cube's HTable, try scan it from
> HBase shell and see if any rows in it. The table is broken then fix HBase.
> If no rows in it, then there must be something wrong during build process.
>
>
> On Wed, May 4, 2016 at 3:05 AM, Phillip Rhodes <pr...@mammothdata.com>
> wrote:
>
>> 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) <ja...@microsoft.com>
>> wrote:
>> > Have you tried to restart your kylin server? This might be a cache
>> problem.
>> >
>> > -----Original Message-----
>> > From: Phillip Rhodes [mailto:prhodes@mammothdata.com]
>> > Sent: Tuesday, May 3, 2016 11:12 AM
>> > To: user@kylin.apache.org
>> > 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 <li...@apache.org> 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
>> >> <pr...@mammothdata.com>
>> >> 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
>> >>
>> >>
>> >
>>
>
>

Re: Kylin sample cube: Cube empty / timeout visiting cube, after build

Posted by Li Yang <li...@apache.org>.
> java.lang.NullPointerException
> at
org.apache.kylin.storage.hbase.cube.v2.CubeHBaseEndpointRPC$1.run(CubeHBaseEndpointRPC.java:326)

This is the cause of query exception. It is further caused by
table.coprocessorService() returning a null. So it seems something wrong
with your HTable.

> the display always shows my cube as being 0.0 bytes

Plus this evidence... Suggest check the cube's HTable, try scan it from
HBase shell and see if any rows in it. The table is broken then fix HBase.
If no rows in it, then there must be something wrong during build process.


On Wed, May 4, 2016 at 3:05 AM, Phillip Rhodes <pr...@mammothdata.com>
wrote:

> 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) <ja...@microsoft.com>
> wrote:
> > Have you tried to restart your kylin server? This might be a cache
> problem.
> >
> > -----Original Message-----
> > From: Phillip Rhodes [mailto:prhodes@mammothdata.com]
> > Sent: Tuesday, May 3, 2016 11:12 AM
> > To: user@kylin.apache.org
> > 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 <li...@apache.org> 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
> >> <pr...@mammothdata.com>
> >> 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
> >>
> >>
> >
>

Re: Kylin sample cube: Cube empty / timeout visiting cube, after build

Posted by Phillip Rhodes <pr...@mammothdata.com>.
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) <ja...@microsoft.com> wrote:
> Have you tried to restart your kylin server? This might be a cache problem.
>
> -----Original Message-----
> From: Phillip Rhodes [mailto:prhodes@mammothdata.com]
> Sent: Tuesday, May 3, 2016 11:12 AM
> To: user@kylin.apache.org
> 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 <li...@apache.org> 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
>> <pr...@mammothdata.com>
>> 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
>>
>>
>

RE: Kylin sample cube: Cube empty / timeout visiting cube, after build

Posted by "Jason Wang (WDG)" <ja...@microsoft.com>.
Have you tried to restart your kylin server? This might be a cache problem.

-----Original Message-----
From: Phillip Rhodes [mailto:prhodes@mammothdata.com] 
Sent: Tuesday, May 3, 2016 11:12 AM
To: user@kylin.apache.org
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 <li...@apache.org> 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 
> <pr...@mammothdata.com>
> 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
>
>

Re: Kylin sample cube: Cube empty / timeout visiting cube, after build

Posted by Phillip Rhodes <pr...@mammothdata.com>.
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 <li...@apache.org> 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 <pr...@mammothdata.com>
> 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(FrameworkServlet.java:789)
>>
>>
>>
>> Thanks,
>>
>>
>> Phil
>
>

Re: Kylin sample cube: Cube empty / timeout visiting cube, after build

Posted by Li Yang <li...@apache.org>.
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 <pr...@mammothdata.com>
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(FrameworkServlet.java:789)
>
>
>
> Thanks,
>
>
> Phil
>