You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@kylin.apache.org by Amit Jain <am...@gmail.com> on 2016/04/29 05:53:16 UTC
Timeout visiting cube!
I'm getting following error when running the query as described on
http://kylin.apache.org/docs15/tutorial/kylin_sample.html
I'm get to the step where the cube build succeeds but when I run the query
on Insight, I get the error.
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!
Please note that I'm running this on a 5 Node Hadoop Cluster with CDH5.7 on
AWS. I tried installing Kylin on datanode first and got this error. Later I
installed Kylin on namenode and still getting the same error message.
I can make Kylin work on CDH VM with single node but not on a Hadoop
multinode cluster.
Please let me know how to fix this.
Thanks
Amit
Re: Timeout visiting cube!
Posted by Amit Jain <am...@gmail.com>.
Hadoop distribution/Version is Cloudera - CDH5.7 / 2.6.0
Hbase version: 1.2.0
On Tue, May 3, 2016 at 11:57 PM, hongbin ma <ma...@apache.org> wrote:
> The "timeout" is not the root cause, it's bad log requires improving (
> https://issues.apache.org/jira/browse/KYLIN-1645)
>
> The root cause seems similar to
>
> http://apache-kylin.74782.x6.nabble.com/Re-My-kylin-cube-build-successfully-but-when-query-I-meetthisexception-td4351.html#none
> ,
> can you share the version of your hadoop, hbase, etc ?
>
> On Wed, May 4, 2016 at 11:49 AM, Amit Jain <am...@gmail.com> wrote:
>
> > Here is the text from kylin.log
> >
> >
> ===============================================================================================================================================
> > 2016-05-03 15:01:51,151 INFO [localhost-startStop-1]
> > core.SpringSecurityCoreVersion:33 : You are running with Spring Security
> > Core 3.1.2.RELEASE
> > 2016-05-03 15:01:51,153 INFO [localhost-startStop-1]
> > config.SecurityNamespaceHandler:59 : Spring Security 'config' module
> > version is 3.1.2.RELEASE
> > 2016-05-03 15:01:51,168 INFO [localhost-startStop-1]
> > method.GlobalMethodSecurityBeanDefinitionParser:141 : Using bean
> > 'expressionHandler' as method ExpressionHandler implementation
> > 2016-05-03 15:01:51,208 INFO [localhost-startStop-1]
> > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> > control expression attribute 'permitAll' for /api/user/authentication*/**
> > 2016-05-03 15:01:51,208 INFO [localhost-startStop-1]
> > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> > control expression attribute 'isAuthenticated()' for /api/query*/**
> > 2016-05-03 15:01:51,208 INFO [localhost-startStop-1]
> > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> > control expression attribute 'isAuthenticated()' for /api/metadata*/**
> > 2016-05-03 15:01:51,208 INFO [localhost-startStop-1]
> > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> > control expression attribute 'permitAll' for /api/**/metrics
> > 2016-05-03 15:01:51,209 INFO [localhost-startStop-1]
> > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> > control expression attribute 'permitAll' for /api/cache*/**
> > 2016-05-03 15:01:51,209 INFO [localhost-startStop-1]
> > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> > control expression attribute 'hasAnyRole('ROLE_ANALYST')' for
> > /api/cubes/src/tables
> > 2016-05-03 15:01:51,209 INFO [localhost-startStop-1]
> > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> > control expression attribute 'isAuthenticated()' for /api/cubes*/**
> > 2016-05-03 15:01:51,209 INFO [localhost-startStop-1]
> > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> > control expression attribute 'isAuthenticated()' for /api/models*/**
> > 2016-05-03 15:01:51,209 INFO [localhost-startStop-1]
> > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> > control expression attribute 'isAuthenticated()' for /api/streaming*/**
> > 2016-05-03 15:01:51,209 INFO [localhost-startStop-1]
> > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> > control expression attribute 'isAuthenticated()' for /api/job*/**
> > 2016-05-03 15:01:51,210 INFO [localhost-startStop-1]
> > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> > control expression attribute 'permitAll' for /api/admin/config
> > 2016-05-03 15:01:51,210 INFO [localhost-startStop-1]
> > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> > control expression attribute 'permitAll' for /api/projects
> > 2016-05-03 15:01:51,210 INFO [localhost-startStop-1]
> > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> > control expression attribute 'hasRole('ROLE_ADMIN')' for /api/admin*/**
> > 2016-05-03 15:01:51,211 INFO [localhost-startStop-1]
> > http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> > control expression attribute 'isAuthenticated()' for /api/**
> > 2016-05-03 15:01:51,225 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.
> > 2016-05-03 15:01:51,229 INFO [localhost-startStop-1]
> > http.HttpSecurityBeanDefinitionParser:264 : Checking sorted filter chain:
> > [Root bean: class
> >
> >
> [org.springframework.security.web.context.SecurityContextPersistenceFilter];
> > scope=; abstract=false; lazyInit=false; autowireMode=0;
> dependencyCheck=0;
> > autowireCandidate=true; primary=false; factoryBeanName=null;
> > factoryMethodName=null; initMethodName=null; destroyMethodName=null,
> order
> > = 300, Root bean: class
> > [org.springframework.security.web.authentication.logout.LogoutFilter];
> > scope=; abstract=false; lazyInit=false; autowireMode=0;
> dependencyCheck=0;
> > autowireCandidate=true; primary=false; factoryBeanName=null;
> > factoryMethodName=null; initMethodName=null; destroyMethodName=null,
> order
> > = 400,
> >
> >
> <org.springframework.security.web.authentication.UsernamePasswordAuthenticationFilter#0>,
> > order = 800, Root bean: class
> >
> >
> [org.springframework.security.web.authentication.ui.DefaultLoginPageGeneratingFilter];
> > scope=; abstract=false; lazyInit=false; autowireMode=0;
> dependencyCheck=0;
> > autowireCandidate=true; primary=false; factoryBeanName=null;
> > factoryMethodName=null; initMethodName=null; destroyMethodName=null,
> order
> > = 1000, Root bean: class
> >
> >
> [org.springframework.security.web.authentication.www.BasicAuthenticationFilter];
> > scope=; abstract=false; lazyInit=false; autowireMode=0;
> dependencyCheck=0;
> > autowireCandidate=true; primary=false; factoryBeanName=null;
> > factoryMethodName=null; initMethodName=null; destroyMethodName=null,
> order
> > = 1200, Root bean: class
> > [org.springframework.security.web.savedrequest.RequestCacheAwareFilter];
> > scope=; abstract=false; lazyInit=false; autowireMode=0;
> dependencyCheck=0;
> > autowireCandidate=true; primary=false; factoryBeanName=null;
> > factoryMethodName=null; initMethodName=null; destroyMethodName=null,
> order
> > = 1300, Root bean: class
> >
> >
> [org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter];
> > scope=; abstract=false; lazyInit=false; autowireMode=0;
> dependencyCheck=0;
> > autowireCandidate=true; primary=false; factoryBeanName=null;
> > factoryMethodName=null; initMethodName=null; destroyMethodName=null,
> order
> > = 1400, Root bean: class
> >
> >
> [org.springframework.security.web.authentication.AnonymousAuthenticationFilter];
> > scope=; abstract=false; lazyInit=false; autowireMode=0;
> dependencyCheck=0;
> > autowireCandidate=true; primary=false; factoryBeanName=null;
> > factoryMethodName=null; initMethodName=null; destroyMethodName=null,
> order
> > = 1700, Root bean: class
> > [org.springframework.security.web.session.SessionManagementFilter];
> scope=;
> > abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0;
> > autowireCandidate=true; primary=false; factoryBeanName=null;
> > factoryMethodName=null; initMethodName=null; destroyMethodName=null,
> order
> > = 1800, Root bean: class
> > [org.springframework.security.web.access.ExceptionTranslationFilter];
> > scope=; abstract=false; lazyInit=false; autowireMode=0;
> dependencyCheck=0;
> > autowireCandidate=true; primary=false; factoryBeanName=null;
> > factoryMethodName=null; initMethodName=null; destroyMethodName=null,
> order
> > = 1900,
> >
> >
> <org.springframework.security.web.access.intercept.FilterSecurityInterceptor#0>,
> > order = 2000]
> > 2016-05-03 15:01:51,418 WARN [localhost-startStop-1]
> > common.KylinConfig:162 : KYLIN_CONF property was not set, will seek
> > KYLIN_HOME env variable
> > 2016-05-03 15:01:51,456 INFO [localhost-startStop-1]
> > security.PasswordPlaceholderConfigurer:177 : Loading properties file from
> > resource loaded through InputStream
> > 2016-05-03 15:01:51,833 INFO [localhost-startStop-1] util.Version:27 :
> > HV000001: Hibernate Validator 5.1.3.Final
> > 2016-05-03 15:01:52,383 INFO [localhost-startStop-1]
> > init.InitialTaskManager:36 : Kylin service is starting.....
> > 2016-05-03 15:01:52,909 INFO [localhost-startStop-1]
> > filter.AnnotationSizeOfFilter:53 : Using regular expression provided
> > through VM argument net.sf.ehcache.pool.sizeof.ignore.pattern for
> > IgnoreSizeOf annotation : ^.*cache\..*IgnoreSizeOf$
> > 2016-05-03 15:01:52,919 INFO [localhost-startStop-1]
> > sizeof.JvmInformation:545 : Detected JVM data model settings of: 64-Bit
> > HotSpot JVM with Compressed OOPs and Concurrent Mark-and-Sweep GC
> > 2016-05-03 15:01:53,151 INFO [localhost-startStop-1]
> > sizeof.AgentLoader:197 : Extracted agent jar to temporary file
> > bin/../tomcat/temp/ehcache-sizeof-agent6516450038826818696.jar
> > 2016-05-03 15:01:53,152 INFO [localhost-startStop-1]
> > sizeof.AgentLoader:137 : Trying to load agent @
> > bin/../tomcat/temp/ehcache-sizeof-agent6516450038826818696.jar
> > 2016-05-03 15:01:53,157 INFO [localhost-startStop-1]
> > impl.DefaultSizeOfEngine:113 : using Agent sizeof engine
> > 2016-05-03 15:01:53,319 INFO [localhost-startStop-1]
> cube.CubeManager:124
> > : Initializing CubeManager with config kylin_metadata@hbase
> > 2016-05-03 15:01:53,322 INFO [localhost-startStop-1]
> > persistence.ResourceStore:82 : Using metadata url kylin_metadata@hbase
> for
> > resource store
> > 2016-05-03 15:01:53,470 INFO [localhost-startStop-1]
> > hbase.HBaseConnection:139 : connection is null or closed, creating a new
> > one
> > 2016-05-03 15:01:54,927 DEBUG [localhost-startStop-1]
> > hbase.HBaseConnection:185 : HTable 'kylin_metadata' already exists
> > 2016-05-03 15:01:54,979 DEBUG [localhost-startStop-1]
> cube.CubeManager:811
> > : Loading Cube from folder
> kylin_metadata(key='/cube')@kylin_metadata@hbase
> > 2016-05-03 15:01:55,064 INFO [localhost-startStop-1]
> > cube.CubeDescManager:91 : Initializing CubeDescManager with config
> > kylin_metadata@hbase
> > 2016-05-03 15:01:55,064 INFO [localhost-startStop-1]
> > cube.CubeDescManager:197 : Reloading Cube Metadata from folder
> > kylin_metadata(key='/cube_desc')@kylin_metadata@hbase
> > 2016-05-03 15:01:55,112 DEBUG [localhost-startStop-1]
> > metadata.MetadataManager:315 : Reloading SourceTable from folder
> > kylin_metadata(key='/table')@kylin_metadata@hbase
> > 2016-05-03 15:01:55,159 DEBUG [localhost-startStop-1]
> > metadata.MetadataManager:324 : Loaded 3 SourceTable(s)
> > 2016-05-03 15:01:55,159 DEBUG [localhost-startStop-1]
> > metadata.MetadataManager:256 : Reloading SourceTable exd info from folder
> > kylin_metadata(key='/table_exd')@kylin_metadata@hbase
> > 2016-05-03 15:01:55,196 DEBUG [localhost-startStop-1]
> > metadata.MetadataManager:265 : Loaded 2 SourceTable EXD(s)
> > 2016-05-03 15:01:55,197 DEBUG [localhost-startStop-1]
> > metadata.MetadataManager:429 : Reloading DataModel from folder
> > kylin_metadata(key='/model_desc')@kylin_metadata@hbase
> > 2016-05-03 15:01:55,224 DEBUG [localhost-startStop-1]
> > metadata.MetadataManager:443 : Loaded 2 DataModel(s)
> > 2016-05-03 15:01:55,225 DEBUG [localhost-startStop-1]
> > metadata.MetadataManager:301 : Reloading ExternalFilter from folder
> > kylin_metadata(key='/ext_filter')@kylin_metadata@hbase
> > 2016-05-03 15:01:55,229 DEBUG [localhost-startStop-1]
> > metadata.MetadataManager:310 : Loaded 0 SourceTable(s)
> > 2016-05-03 15:01:55,246 DEBUG [localhost-startStop-1]
> > cube.CubeDescManager:222 : Loaded 2 Cube(s)
> > 2016-05-03 15:01:55,256 DEBUG [localhost-startStop-1]
> cube.CubeManager:846
> > : Reloaded new cube: Tutorial_Cube with reference
> > beingCUBE[name=Tutorial_Cube] having 1 segments:KYLIN_3UZ4ZT17UO
> > 2016-05-03 15:01:55,259 DEBUG [localhost-startStop-1]
> cube.CubeManager:846
> > : Reloaded new cube: kylin_sales_cube with reference
> > beingCUBE[name=kylin_sales_cube] having 2
> > segments:KYLIN_K7Z527EQEX,KYLIN_ENZP3OPKTE
> > 2016-05-03 15:01:55,259 DEBUG [localhost-startStop-1]
> cube.CubeManager:817
> > : Loaded 2 Cube(s)
> > 2016-05-03 15:01:55,353 INFO [localhost-startStop-1]
> > controller.JobController:83 : Initializing Job Engine ....
> > 2016-05-03 15:01:55,358 INFO [Thread-11] manager.ExecutableManager:73 :
> > Using metadata url: kylin_metadata@hbase
> > 2016-05-03 15:01:55,363 INFO [Thread-11] dao.ExecutableDao:65 : Using
> > metadata url: kylin_metadata@hbase
> > 2016-05-03 15:01:55,393 INFO [localhost-startStop-1]
> > badquery.BadQueryHistoryManager:42 : Initializing BadQueryHistoryManager
> > with config kylin_metadata@hbase
> > 2016-05-03 15:01:55,950 INFO [localhost-startStop-1]
> > web.DefaultSecurityFilterChain:28 : Creating filter chain:
> > org.springframework.security.web.util.AnyRequestMatcher@1,
> >
> >
> [org.springframework.security.web.context.SecurityContextPersistenceFilter@4a349ef9
> > ,
> >
> >
> org.springframework.security.web.authentication.logout.LogoutFilter@44b1137e
> > ,
> >
> >
> org.springframework.security.web.authentication.UsernamePasswordAuthenticationFilter@4576d86d
> > ,
> >
> >
> org.springframework.security.web.authentication.ui.DefaultLoginPageGeneratingFilter@7d7affbb
> > ,
> >
> >
> org.springframework.security.web.authentication.www.BasicAuthenticationFilter@162b8e59
> > ,
> >
> >
> org.springframework.security.web.savedrequest.RequestCacheAwareFilter@58e806e
> > ,
> >
> >
> org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter@4e91b89b
> > ,
> >
> >
> org.springframework.security.web.authentication.AnonymousAuthenticationFilter@40eef069
> > ,
> > org.springframework.security.web.session.SessionManagementFilter@6e99ecd
> ,
> >
> org.springframework.security.web.access.ExceptionTranslationFilter@53b8a846
> > ,
> >
> >
> org.springframework.security.web.access.intercept.FilterSecurityInterceptor@2fb69b
> > ]
> > 2016-05-03 15:02:05,485 INFO [pool-5-thread-1]
> > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual
> running,
> > 0 ready, 6 others
> > 2016-05-03 15:02:39,492 DEBUG [http-bio-7070-exec-9]
> > service.AdminService:90 : Get Kylin Runtime Config
> > 2016-05-03 15:02:41,280 DEBUG [http-bio-7070-exec-1]
> > 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 15:02:41,369 DEBUG [http-bio-7070-exec-3]
> > 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 15:02:41,515 INFO [http-bio-7070-exec-3]
> > project.ProjectManager:88 : Initializing ProjectManager with metadata url
> > kylin_metadata@hbase
> > 2016-05-03 15:02:41,529 DEBUG [http-bio-7070-exec-3]
> > project.ProjectManager:104 : Loading Project from folder
> > kylin_metadata(key='/project')@kylin_metadata@hbase
> > 2016-05-03 15:02:41,540 DEBUG [http-bio-7070-exec-3]
> > project.ProjectManager:109 : Loaded 2 Project(s)
> > 2016-05-03 15:02:41,540 DEBUG [http-bio-7070-exec-3]
> > controller.ProjectController:97 : 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 15:02:41,731 INFO [http-bio-7070-exec-9]
> > kafka.KafkaConfigManager:204 : Reloading Kafka Metadata from folder
> > kylin_metadata(key='/kafka')@kylin_metadata@hbase
> > 2016-05-03 15:02:41,735 DEBUG [http-bio-7070-exec-9]
> > kafka.KafkaConfigManager:229 : Loaded 0 KafkaConfig(s)
> > 2016-05-03 15:02:41,743 INFO [http-bio-7070-exec-10]
> > streaming.StreamingManager:218 : Reloading Streaming Metadata from folder
> > kylin_metadata(key='/streaming')@kylin_metadata@hbase
> > 2016-05-03 15:02:41,751 DEBUG [http-bio-7070-exec-10]
> > streaming.StreamingManager:243 : Loaded 0 StreamingConfig(s)
> > 2016-05-03 15:02:49,864 INFO [http-bio-7070-exec-10]
> > realization.RealizationRegistry:75 : Initializing RealizationRegistry
> with
> > metadata url kylin_metadata@hbase
> > 2016-05-03 15:02:49,864 INFO [http-bio-7070-exec-1]
> > project.ProjectL2Cache:172 : Loading L2 project cache for Tutorial
> > 2016-05-03 15:02:50,239 INFO [http-bio-7070-exec-10]
> > reflections.Reflections:224 : Reflections took 358 ms to scan 18 urls,
> > producing 274 keys and 2131 values
> > 2016-05-03 15:02:50,243 INFO [http-bio-7070-exec-10]
> > hybrid.HybridManager:82 : Initializing HybridManager with config
> > kylin_metadata@hbase
> > 2016-05-03 15:02:50,248 DEBUG [http-bio-7070-exec-10]
> > hybrid.HybridManager:92 : Loading Hybrid from folder
> > kylin_metadata(key='/hybrid')@kylin_metadata@hbase
> > 2016-05-03 15:02:50,248 DEBUG [http-bio-7070-exec-10]
> > hybrid.HybridManager:98 : Loaded 0 Hybrid(s)
> > 2016-05-03 15:02:50,250 INFO [http-bio-7070-exec-10]
> > invertedindex.IIManager:101 : Initializing IIManager with config
> > kylin_metadata@hbase
> > 2016-05-03 15:02:50,255 DEBUG [http-bio-7070-exec-10]
> > invertedindex.IIManager:226 : Loading II from folder
> > kylin_metadata(key='/invertedindex')@kylin_metadata@hbase
> > 2016-05-03 15:02:50,255 DEBUG [http-bio-7070-exec-10]
> > invertedindex.IIManager:232 : Loaded 0 II(s)
> > 2016-05-03 15:02:50,258 INFO [http-bio-7070-exec-10]
> > realization.RealizationRegistry:103 : RealizationRegistry is
> > {CUBE=org.apache.kylin.cube.CubeManager@46c95a9d,
> > HYBRID=org.apache.kylin.storage.hybrid.HybridManager@4d5c1db9,
> > INVERTED_INDEX=org.apache.kylin.invertedindex.IIManager@5aa3845c}
> > 2016-05-03 15:02:50,260 INFO [http-bio-7070-exec-1]
> > controller.TableController:89 : Return all table metadata in 396 seconds
> > 2016-05-03 15:02:54,910 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 15:02:55,053 DEBUG [http-bio-7070-exec-10]
> > service.CacheService:144 : Creating a new data source, OLAP data source
> > pointing to kylin_metadata@hbase
> > 2016-05-03 15:02:55,055 INFO [http-bio-7070-exec-10]
> > project.ProjectL2Cache:172 : Loading L2 project cache for TUTORIAL
> > 2016-05-03 15:02:55,060 INFO [http-bio-7070-exec-10]
> > schema.OLAPSchemaFactory:135 : Schema json:{
> > "version": "1.0",
> > "defaultSchema": "DEFAULT",
> > "schemas": [
> > {
> > "type": "custom",
> > "name": "DEFAULT",
> > "factory": "org.apache.kylin.query.schema.OLAPSchemaFactory",
> > "operand": {
> > "project": "TUTORIAL"
> > },
> > "functions": [
> > {
> > name: 'MASSIN',
> > className: 'org.apache.kylin.query.udf.MassInUDF'
> > }
> > ]
> > }
> > ]
> > }
> > 2016-05-03 15:02:55,061 DEBUG [http-bio-7070-exec-10]
> > 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": "TUTORIAL"
> > },
> > "functions": [
> > {
> > name: 'MASSIN',
> > className: 'org.apache.kylin.query.udf.MassInUDF'
> > }
> > ]
> > }
> > ]
> > }
> >
> > 2016-05-03 15:02:55,368 DEBUG [http-bio-7070-exec-10]
> > service.QueryService:289 : getting table metas
> > 2016-05-03 15:02:55,416 DEBUG [http-bio-7070-exec-10]
> > service.QueryService:307 : getting column metas
> > 2016-05-03 15:02:55,454 DEBUG [http-bio-7070-exec-10]
> > service.QueryService:321 : done column metas
> > 2016-05-03 15:03:05,474 INFO [pool-5-thread-1]
> > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual
> running,
> > 0 ready, 6 others
> > 2016-05-03 15:03:20,325 INFO [http-bio-7070-exec-5]
> > controller.QueryController:174 : Using project: Tutorial
> > 2016-05-03 15:03:20,326 INFO [http-bio-7070-exec-5]
> > 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 LIMIT 50000
> > 2016-05-03 15:03:20,825 INFO [http-bio-7070-exec-5]
> routing.QueryRouter:48
> > : The project manager's reference is
> > org.apache.kylin.metadata.project.ProjectManager@972713a
> > 2016-05-03 15:03:20,826 INFO [http-bio-7070-exec-5]
> routing.QueryRouter:60
> > : Find candidates by table DEFAULT.KYLIN_SALES and project=TUTORIAL :
> > org.apache.kylin.query.routing.Candidate@6ab02f87
> > 2016-05-03 15:03:20,828 INFO [http-bio-7070-exec-5]
> routing.QueryRouter:49
> > : Applying rule: class
> > org.apache.kylin.query.routing.rules.RemoveUncapableRealizationsRule,
> > realizations before: [Tutorial_Cube(CUBE)], realizations after:
> > [Tutorial_Cube(CUBE)]
> > 2016-05-03 15:03:20,829 INFO [http-bio-7070-exec-5]
> routing.QueryRouter:49
> > : Applying rule: class
> > org.apache.kylin.query.routing.rules.RealizationSortRule, realizations
> > before: [Tutorial_Cube(CUBE)], realizations after: [Tutorial_Cube(CUBE)]
> > 2016-05-03 15:03:20,829 INFO [http-bio-7070-exec-5]
> routing.QueryRouter:72
> > : The realizations remaining: [Tutorial_Cube(CUBE)] And the final chosen
> > one is the first one
> > 2016-05-03 15:03:21,076 DEBUG [http-bio-7070-exec-5]
> > enumerator.OLAPEnumerator:107 : query storage...
> > 2016-05-03 15:03:21,090 INFO [http-bio-7070-exec-5]
> > v2.CubeStorageQuery:251 : exactAggregation is true
> > 2016-05-03 15:03:21,093 INFO [http-bio-7070-exec-5]
> > v2.CubeStorageQuery:357 : Memory budget is set to: 784134
> > 2016-05-03 15:03:21,105 INFO [http-bio-7070-exec-5]
> > dict.DictionaryManager:385 : DictionaryManager(91588327) loading
> > DictionaryInfo(loadDictObj:true) at
> >
> /dict/DEFAULT.KYLIN_CAL_DT/CAL_DT/7f549503-b7f8-4ac9-b826-0f2ceaed1304.dict
> > 2016-05-03 15:03:21,134 DEBUG [http-bio-7070-exec-5]
> > v2.CubeHBaseEndpointRPC:256 : New scanner for current segment
> > Tutorial_Cube[20111230000000_20160430000000] will use
> > SCAN_FILTER_AGGR_CHECKMEM as endpoint's behavior
> > 2016-05-03 15:03:21,148 DEBUG [http-bio-7070-exec-5]
> > v2.CubeHBaseEndpointRPC:291 : Serialized scanRequestBytes 320 bytes,
> > rawScanBytesString 46 bytes
> > 2016-05-03 15:03:21,148 INFO [http-bio-7070-exec-5]
> > 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 15:03:21,150 INFO [http-bio-7070-exec-5] v2.CubeHBaseRPC:309
> :
> > Visiting hbase table KYLIN_3UZ4ZT17UO: cuboid exact match, from 8 to 8
> > Start: \x00\x00\x00\x00\x00\x00\x00\x00\x00\x08\x00\x00\x00
> > (\x00\x00\x00\x00\x00\x00\x00\x00\x00\x08\x00\x00\x00) Stop:
> > \x00\x00\x00\x00\x00\x00\x00\x00\x00\x08\xFF\xFF\xFF\x00
> > (\x00\x00\x00\x00\x00\x00\x00\x00\x00\x08\xFF\xFF\xFF\x00), No Fuzzy Key
> > 2016-05-03 15:03:21,150 DEBUG [http-bio-7070-exec-5]
> > v2.CubeHBaseEndpointRPC:300 : Submitting rpc to 1 shards starting from
> > shard 1, scan requests count 1
> > 2016-05-03 15:03:21,220 INFO [http-bio-7070-exec-5]
> > v2.CubeHBaseEndpointRPC:125 : Timeout for ExpectedSizeIterator is 60000
> > 2016-05-03 15:03:21,227 DEBUG [http-bio-7070-exec-5]
> > enumerator.OLAPEnumerator:127 : return TupleIterator...
> > 2016-05-03 15:03:21,284 ERROR [pool-7-thread-1]
> v2.CubeHBaseEndpointRPC:98
> > : Execution exception when running task in pool-7-thread-1
> > 2016-05-03 15:03:21,285 ERROR [pool-7-thread-1]
> v2.CubeHBaseEndpointRPC:108
> > : Caught exception in thread pool-7-thread-1:
> > java.lang.RuntimeException: Error when visiting cubes by endpoint:
> > at
> >
> >
> org.apache.kylin.storage.hbase.cube.v2.CubeHBaseEndpointRPC$1.run(CubeHBaseEndpointRPC.java:323)
> > at
> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
> > at java.util.concurrent.FutureTask.run(FutureTask.java:262)
> > at
> >
> >
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
> > at
> >
> >
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
> > at java.lang.Thread.run(Thread.java:745)
> > Caused by: org.apache.hadoop.hbase.DoNotRetryIOException:
> > org.apache.hadoop.hbase.DoNotRetryIOException:
> java.lang.NoSuchMethodError:
> >
> >
> org.apache.hadoop.hbase.coprocessor.RegionCoprocessorEnvironment.getRegion()Lorg/apache/hadoop/hbase/regionserver/HRegion;
> > at org.apache.hadoop.hbase.ipc.RpcServer.call(RpcServer.java:2212)
> > at org.apache.hadoop.hbase.ipc.CallRunner.run(CallRunner.java:109)
> > at
> >
> org.apache.hadoop.hbase.ipc.RpcExecutor.consumerLoop(RpcExecutor.java:133)
> > at org.apache.hadoop.hbase.ipc.RpcExecutor$1.run(RpcExecutor.java:108)
> > at java.lang.Thread.run(Thread.java:745)
> > Caused by: java.lang.NoSuchMethodError:
> >
> >
> org.apache.hadoop.hbase.coprocessor.RegionCoprocessorEnvironment.getRegion()Lorg/apache/hadoop/hbase/regionserver/HRegion;
> > at
> >
> >
> org.apache.kylin.storage.hbase.cube.v2.coprocessor.endpoint.CubeVisitService.visitCube(CubeVisitService.java:158)
> > at
> >
> >
> org.apache.kylin.storage.hbase.cube.v2.coprocessor.endpoint.generated.CubeVisitProtos$CubeVisitService.callMethod(CubeVisitProtos.java:3800)
> > at
> >
> org.apache.hadoop.hbase.regionserver.HRegion.execService(HRegion.java:7789)
> > at
> >
> >
> org.apache.hadoop.hbase.regionserver.RSRpcServices.execServiceOnRegion(RSRpcServices.java:1988)
> > at
> >
> >
> org.apache.hadoop.hbase.regionserver.RSRpcServices.execService(RSRpcServices.java:1970)
> > at
> >
> >
> org.apache.hadoop.hbase.protobuf.generated.ClientProtos$ClientService$2.callBlockingMethod(ClientProtos.java:33652)
> > at org.apache.hadoop.hbase.ipc.RpcServer.call(RpcServer.java:2170)
> > ... 4 more
> >
> > at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
> > at
> >
> >
> sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
> > at
> >
> >
> sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
> > at java.lang.reflect.Constructor.newInstance(Constructor.java:526)
> > at
> >
> >
> org.apache.hadoop.ipc.RemoteException.instantiateException(RemoteException.java:106)
> > at
> >
> >
> org.apache.hadoop.ipc.RemoteException.unwrapRemoteException(RemoteException.java:95)
> > at
> >
> >
> org.apache.hadoop.hbase.protobuf.ProtobufUtil.getRemoteException(ProtobufUtil.java:327)
> > at
> >
> >
> org.apache.hadoop.hbase.protobuf.ProtobufUtil.execService(ProtobufUtil.java:1624)
> > at
> >
> >
> org.apache.hadoop.hbase.ipc.RegionCoprocessorRpcChannel$1.call(RegionCoprocessorRpcChannel.java:92)
> > at
> >
> >
> org.apache.hadoop.hbase.ipc.RegionCoprocessorRpcChannel$1.call(RegionCoprocessorRpcChannel.java:89)
> > at
> >
> >
> org.apache.hadoop.hbase.client.RpcRetryingCaller.callWithRetries(RpcRetryingCaller.java:126)
> > at
> >
> >
> org.apache.hadoop.hbase.ipc.RegionCoprocessorRpcChannel.callExecService(RegionCoprocessorRpcChannel.java:95)
> > at
> >
> >
> org.apache.hadoop.hbase.ipc.CoprocessorRpcChannel.callMethod(CoprocessorRpcChannel.java:56)
> > at
> >
> >
> org.apache.kylin.storage.hbase.cube.v2.coprocessor.endpoint.generated.CubeVisitProtos$CubeVisitService$Stub.visitCube(CubeVisitProtos.java:3861)
> > at
> >
> >
> org.apache.kylin.storage.hbase.cube.v2.CubeHBaseEndpointRPC$2.call(CubeHBaseEndpointRPC.java:361)
> > at
> >
> >
> org.apache.kylin.storage.hbase.cube.v2.CubeHBaseEndpointRPC$2.call(CubeHBaseEndpointRPC.java:357)
> > at org.apache.hadoop.hbase.client.HTable$15.call(HTable.java:1797)
> > ... 4 more
> > Caused by:
> >
> >
> org.apache.hadoop.hbase.ipc.RemoteWithExtrasException(org.apache.hadoop.hbase.DoNotRetryIOException):
> > org.apache.hadoop.hbase.DoNotRetryIOException:
> java.lang.NoSuchMethodError:
> >
> >
> org.apache.hadoop.hbase.coprocessor.RegionCoprocessorEnvironment.getRegion()Lorg/apache/hadoop/hbase/regionserver/HRegion;
> > at org.apache.hadoop.hbase.ipc.RpcServer.call(RpcServer.java:2212)
> > at org.apache.hadoop.hbase.ipc.CallRunner.run(CallRunner.java:109)
> > at
> >
> org.apache.hadoop.hbase.ipc.RpcExecutor.consumerLoop(RpcExecutor.java:133)
> > at org.apache.hadoop.hbase.ipc.RpcExecutor$1.run(RpcExecutor.java:108)
> > at java.lang.Thread.run(Thread.java:745)
> > Caused by: java.lang.NoSuchMethodError:
> >
> >
> org.apache.hadoop.hbase.coprocessor.RegionCoprocessorEnvironment.getRegion()Lorg/apache/hadoop/hbase/regionserver/HRegion;
> > at
> >
> >
> org.apache.kylin.storage.hbase.cube.v2.coprocessor.endpoint.CubeVisitService.visitCube(CubeVisitService.java:158)
> > at
> >
> >
> org.apache.kylin.storage.hbase.cube.v2.coprocessor.endpoint.generated.CubeVisitProtos$CubeVisitService.callMethod(CubeVisitProtos.java:3800)
> > at
> >
> org.apache.hadoop.hbase.regionserver.HRegion.execService(HRegion.java:7789)
> > at
> >
> >
> org.apache.hadoop.hbase.regionserver.RSRpcServices.execServiceOnRegion(RSRpcServices.java:1988)
> > at
> >
> >
> org.apache.hadoop.hbase.regionserver.RSRpcServices.execService(RSRpcServices.java:1970)
> > at
> >
> >
> org.apache.hadoop.hbase.protobuf.generated.ClientProtos$ClientService$2.callBlockingMethod(ClientProtos.java:33652)
> > at org.apache.hadoop.hbase.ipc.RpcServer.call(RpcServer.java:2170)
> > ... 4 more
> >
> > at
> org.apache.hadoop.hbase.ipc.RpcClientImpl.call(RpcClientImpl.java:1268)
> > at
> >
> >
> org.apache.hadoop.hbase.ipc.AbstractRpcClient.callBlockingMethod(AbstractRpcClient.java:226)
> > at
> >
> >
> org.apache.hadoop.hbase.ipc.AbstractRpcClient$BlockingRpcChannelImplementation.callBlockingMethod(AbstractRpcClient.java:331)
> > at
> >
> >
> org.apache.hadoop.hbase.protobuf.generated.ClientProtos$ClientService$BlockingStub.execService(ClientProtos.java:34118)
> > at
> >
> >
> org.apache.hadoop.hbase.protobuf.ProtobufUtil.execService(ProtobufUtil.java:1620)
> > ... 13 more
> > 2016-05-03 15:04:05,474 INFO [pool-5-thread-1]
> > threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual
> running,
> > 0 ready, 6 others
> > 2016-05-03 15:04:21,229 ERROR [http-bio-7070-exec-5]
> > 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$$f73318de.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:57)
> > at
> >
> >
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> > at java.lang.reflect.Method.invoke(Method.java:606)
> > 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:201)
> > 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:1145)
> > at
> >
> >
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
> > 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 15:04:21,233 INFO [http-bio-7070-exec-5]
> > 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 LIMIT 50000
> > User: ADMIN
> > Success: false
> > Duration: 0.0
> > Project: Tutorial
> > Realization Names: [Tutorial_Cube]
> > Cuboid Ids: [8]
> > 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 15:04:21,237 ERROR [http-bio-7070-exec-5]
> > 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:57)
> > at
> >
> >
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> > at java.lang.reflect.Method.invoke(Method.java:606)
> > 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:201)
> > 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:1145)
> > at
> >
> >
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
> > at
> >
> >
> org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
> > at java.lang.Thread.run(Thread.java:745)
> >
> >
> >
> ===============================================================================================================================================
> >
> > On Tue, May 3, 2016 at 9:56 PM, hongbin ma <ma...@apache.org> wrote:
> >
> > > Hi Amit
> > >
> > > seems no attachment is found
> > >
> > > On Tue, May 3, 2016 at 11:09 PM, Amit Jain <am...@gmail.com> wrote:
> > >
> > > > Hi Li,
> > > > Log file is attached. Please let me know your findings.
> > > > Thanks for your help.
> > > > Amit
> > > >
> > > > On Tue, May 3, 2016 at 5:00 AM, Li Yang <li...@apache.org> wrote:
> > > >
> > > >> Could you share kylin.log? There must be something else behind the
> > > timeout
> > > >> error.
> > > >>
> > > >> On Fri, Apr 29, 2016 at 11:53 AM, Amit Jain <am...@gmail.com>
> wrote:
> > > >>
> > > >> > I'm getting following error when running the query as described on
> > > >> > http://kylin.apache.org/docs15/tutorial/kylin_sample.html
> > > >> > I'm get to the step where the cube build succeeds but when I run
> the
> > > >> query
> > > >> > on Insight, I get the error.
> > > >> >
> > > >> > 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!
> > > >> >
> > > >> > Please note that I'm running this on a 5 Node Hadoop Cluster with
> > > >> CDH5.7 on
> > > >> > AWS. I tried installing Kylin on datanode first and got this
> error.
> > > >> Later I
> > > >> > installed Kylin on namenode and still getting the same error
> > message.
> > > >> > I can make Kylin work on CDH VM with single node but not on a
> Hadoop
> > > >> > multinode cluster.
> > > >> > Please let me know how to fix this.
> > > >> > Thanks
> > > >> > Amit
> > > >> >
> > > >>
> > > >
> > > >
> > >
> > >
> > > --
> > > Regards,
> > >
> > > *Bin Mahone | 马洪宾*
> > > Apache Kylin: http://kylin.io
> > > Github: https://github.com/binmahone
> > >
> >
>
>
>
> --
> Regards,
>
> *Bin Mahone | 马洪宾*
> Apache Kylin: http://kylin.io
> Github: https://github.com/binmahone
>
Re: Timeout visiting cube!
Posted by hongbin ma <ma...@apache.org>.
The "timeout" is not the root cause, it's bad log requires improving (
https://issues.apache.org/jira/browse/KYLIN-1645)
The root cause seems similar to
http://apache-kylin.74782.x6.nabble.com/Re-My-kylin-cube-build-successfully-but-when-query-I-meetthisexception-td4351.html#none,
can you share the version of your hadoop, hbase, etc ?
On Wed, May 4, 2016 at 11:49 AM, Amit Jain <am...@gmail.com> wrote:
> Here is the text from kylin.log
>
> ===============================================================================================================================================
> 2016-05-03 15:01:51,151 INFO [localhost-startStop-1]
> core.SpringSecurityCoreVersion:33 : You are running with Spring Security
> Core 3.1.2.RELEASE
> 2016-05-03 15:01:51,153 INFO [localhost-startStop-1]
> config.SecurityNamespaceHandler:59 : Spring Security 'config' module
> version is 3.1.2.RELEASE
> 2016-05-03 15:01:51,168 INFO [localhost-startStop-1]
> method.GlobalMethodSecurityBeanDefinitionParser:141 : Using bean
> 'expressionHandler' as method ExpressionHandler implementation
> 2016-05-03 15:01:51,208 INFO [localhost-startStop-1]
> http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> control expression attribute 'permitAll' for /api/user/authentication*/**
> 2016-05-03 15:01:51,208 INFO [localhost-startStop-1]
> http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> control expression attribute 'isAuthenticated()' for /api/query*/**
> 2016-05-03 15:01:51,208 INFO [localhost-startStop-1]
> http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> control expression attribute 'isAuthenticated()' for /api/metadata*/**
> 2016-05-03 15:01:51,208 INFO [localhost-startStop-1]
> http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> control expression attribute 'permitAll' for /api/**/metrics
> 2016-05-03 15:01:51,209 INFO [localhost-startStop-1]
> http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> control expression attribute 'permitAll' for /api/cache*/**
> 2016-05-03 15:01:51,209 INFO [localhost-startStop-1]
> http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> control expression attribute 'hasAnyRole('ROLE_ANALYST')' for
> /api/cubes/src/tables
> 2016-05-03 15:01:51,209 INFO [localhost-startStop-1]
> http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> control expression attribute 'isAuthenticated()' for /api/cubes*/**
> 2016-05-03 15:01:51,209 INFO [localhost-startStop-1]
> http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> control expression attribute 'isAuthenticated()' for /api/models*/**
> 2016-05-03 15:01:51,209 INFO [localhost-startStop-1]
> http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> control expression attribute 'isAuthenticated()' for /api/streaming*/**
> 2016-05-03 15:01:51,209 INFO [localhost-startStop-1]
> http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> control expression attribute 'isAuthenticated()' for /api/job*/**
> 2016-05-03 15:01:51,210 INFO [localhost-startStop-1]
> http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> control expression attribute 'permitAll' for /api/admin/config
> 2016-05-03 15:01:51,210 INFO [localhost-startStop-1]
> http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> control expression attribute 'permitAll' for /api/projects
> 2016-05-03 15:01:51,210 INFO [localhost-startStop-1]
> http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> control expression attribute 'hasRole('ROLE_ADMIN')' for /api/admin*/**
> 2016-05-03 15:01:51,211 INFO [localhost-startStop-1]
> http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
> control expression attribute 'isAuthenticated()' for /api/**
> 2016-05-03 15:01:51,225 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.
> 2016-05-03 15:01:51,229 INFO [localhost-startStop-1]
> http.HttpSecurityBeanDefinitionParser:264 : Checking sorted filter chain:
> [Root bean: class
>
> [org.springframework.security.web.context.SecurityContextPersistenceFilter];
> scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0;
> autowireCandidate=true; primary=false; factoryBeanName=null;
> factoryMethodName=null; initMethodName=null; destroyMethodName=null, order
> = 300, Root bean: class
> [org.springframework.security.web.authentication.logout.LogoutFilter];
> scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0;
> autowireCandidate=true; primary=false; factoryBeanName=null;
> factoryMethodName=null; initMethodName=null; destroyMethodName=null, order
> = 400,
>
> <org.springframework.security.web.authentication.UsernamePasswordAuthenticationFilter#0>,
> order = 800, Root bean: class
>
> [org.springframework.security.web.authentication.ui.DefaultLoginPageGeneratingFilter];
> scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0;
> autowireCandidate=true; primary=false; factoryBeanName=null;
> factoryMethodName=null; initMethodName=null; destroyMethodName=null, order
> = 1000, Root bean: class
>
> [org.springframework.security.web.authentication.www.BasicAuthenticationFilter];
> scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0;
> autowireCandidate=true; primary=false; factoryBeanName=null;
> factoryMethodName=null; initMethodName=null; destroyMethodName=null, order
> = 1200, Root bean: class
> [org.springframework.security.web.savedrequest.RequestCacheAwareFilter];
> scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0;
> autowireCandidate=true; primary=false; factoryBeanName=null;
> factoryMethodName=null; initMethodName=null; destroyMethodName=null, order
> = 1300, Root bean: class
>
> [org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter];
> scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0;
> autowireCandidate=true; primary=false; factoryBeanName=null;
> factoryMethodName=null; initMethodName=null; destroyMethodName=null, order
> = 1400, Root bean: class
>
> [org.springframework.security.web.authentication.AnonymousAuthenticationFilter];
> scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0;
> autowireCandidate=true; primary=false; factoryBeanName=null;
> factoryMethodName=null; initMethodName=null; destroyMethodName=null, order
> = 1700, Root bean: class
> [org.springframework.security.web.session.SessionManagementFilter]; scope=;
> abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0;
> autowireCandidate=true; primary=false; factoryBeanName=null;
> factoryMethodName=null; initMethodName=null; destroyMethodName=null, order
> = 1800, Root bean: class
> [org.springframework.security.web.access.ExceptionTranslationFilter];
> scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0;
> autowireCandidate=true; primary=false; factoryBeanName=null;
> factoryMethodName=null; initMethodName=null; destroyMethodName=null, order
> = 1900,
>
> <org.springframework.security.web.access.intercept.FilterSecurityInterceptor#0>,
> order = 2000]
> 2016-05-03 15:01:51,418 WARN [localhost-startStop-1]
> common.KylinConfig:162 : KYLIN_CONF property was not set, will seek
> KYLIN_HOME env variable
> 2016-05-03 15:01:51,456 INFO [localhost-startStop-1]
> security.PasswordPlaceholderConfigurer:177 : Loading properties file from
> resource loaded through InputStream
> 2016-05-03 15:01:51,833 INFO [localhost-startStop-1] util.Version:27 :
> HV000001: Hibernate Validator 5.1.3.Final
> 2016-05-03 15:01:52,383 INFO [localhost-startStop-1]
> init.InitialTaskManager:36 : Kylin service is starting.....
> 2016-05-03 15:01:52,909 INFO [localhost-startStop-1]
> filter.AnnotationSizeOfFilter:53 : Using regular expression provided
> through VM argument net.sf.ehcache.pool.sizeof.ignore.pattern for
> IgnoreSizeOf annotation : ^.*cache\..*IgnoreSizeOf$
> 2016-05-03 15:01:52,919 INFO [localhost-startStop-1]
> sizeof.JvmInformation:545 : Detected JVM data model settings of: 64-Bit
> HotSpot JVM with Compressed OOPs and Concurrent Mark-and-Sweep GC
> 2016-05-03 15:01:53,151 INFO [localhost-startStop-1]
> sizeof.AgentLoader:197 : Extracted agent jar to temporary file
> bin/../tomcat/temp/ehcache-sizeof-agent6516450038826818696.jar
> 2016-05-03 15:01:53,152 INFO [localhost-startStop-1]
> sizeof.AgentLoader:137 : Trying to load agent @
> bin/../tomcat/temp/ehcache-sizeof-agent6516450038826818696.jar
> 2016-05-03 15:01:53,157 INFO [localhost-startStop-1]
> impl.DefaultSizeOfEngine:113 : using Agent sizeof engine
> 2016-05-03 15:01:53,319 INFO [localhost-startStop-1] cube.CubeManager:124
> : Initializing CubeManager with config kylin_metadata@hbase
> 2016-05-03 15:01:53,322 INFO [localhost-startStop-1]
> persistence.ResourceStore:82 : Using metadata url kylin_metadata@hbase for
> resource store
> 2016-05-03 15:01:53,470 INFO [localhost-startStop-1]
> hbase.HBaseConnection:139 : connection is null or closed, creating a new
> one
> 2016-05-03 15:01:54,927 DEBUG [localhost-startStop-1]
> hbase.HBaseConnection:185 : HTable 'kylin_metadata' already exists
> 2016-05-03 15:01:54,979 DEBUG [localhost-startStop-1] cube.CubeManager:811
> : Loading Cube from folder kylin_metadata(key='/cube')@kylin_metadata@hbase
> 2016-05-03 15:01:55,064 INFO [localhost-startStop-1]
> cube.CubeDescManager:91 : Initializing CubeDescManager with config
> kylin_metadata@hbase
> 2016-05-03 15:01:55,064 INFO [localhost-startStop-1]
> cube.CubeDescManager:197 : Reloading Cube Metadata from folder
> kylin_metadata(key='/cube_desc')@kylin_metadata@hbase
> 2016-05-03 15:01:55,112 DEBUG [localhost-startStop-1]
> metadata.MetadataManager:315 : Reloading SourceTable from folder
> kylin_metadata(key='/table')@kylin_metadata@hbase
> 2016-05-03 15:01:55,159 DEBUG [localhost-startStop-1]
> metadata.MetadataManager:324 : Loaded 3 SourceTable(s)
> 2016-05-03 15:01:55,159 DEBUG [localhost-startStop-1]
> metadata.MetadataManager:256 : Reloading SourceTable exd info from folder
> kylin_metadata(key='/table_exd')@kylin_metadata@hbase
> 2016-05-03 15:01:55,196 DEBUG [localhost-startStop-1]
> metadata.MetadataManager:265 : Loaded 2 SourceTable EXD(s)
> 2016-05-03 15:01:55,197 DEBUG [localhost-startStop-1]
> metadata.MetadataManager:429 : Reloading DataModel from folder
> kylin_metadata(key='/model_desc')@kylin_metadata@hbase
> 2016-05-03 15:01:55,224 DEBUG [localhost-startStop-1]
> metadata.MetadataManager:443 : Loaded 2 DataModel(s)
> 2016-05-03 15:01:55,225 DEBUG [localhost-startStop-1]
> metadata.MetadataManager:301 : Reloading ExternalFilter from folder
> kylin_metadata(key='/ext_filter')@kylin_metadata@hbase
> 2016-05-03 15:01:55,229 DEBUG [localhost-startStop-1]
> metadata.MetadataManager:310 : Loaded 0 SourceTable(s)
> 2016-05-03 15:01:55,246 DEBUG [localhost-startStop-1]
> cube.CubeDescManager:222 : Loaded 2 Cube(s)
> 2016-05-03 15:01:55,256 DEBUG [localhost-startStop-1] cube.CubeManager:846
> : Reloaded new cube: Tutorial_Cube with reference
> beingCUBE[name=Tutorial_Cube] having 1 segments:KYLIN_3UZ4ZT17UO
> 2016-05-03 15:01:55,259 DEBUG [localhost-startStop-1] cube.CubeManager:846
> : Reloaded new cube: kylin_sales_cube with reference
> beingCUBE[name=kylin_sales_cube] having 2
> segments:KYLIN_K7Z527EQEX,KYLIN_ENZP3OPKTE
> 2016-05-03 15:01:55,259 DEBUG [localhost-startStop-1] cube.CubeManager:817
> : Loaded 2 Cube(s)
> 2016-05-03 15:01:55,353 INFO [localhost-startStop-1]
> controller.JobController:83 : Initializing Job Engine ....
> 2016-05-03 15:01:55,358 INFO [Thread-11] manager.ExecutableManager:73 :
> Using metadata url: kylin_metadata@hbase
> 2016-05-03 15:01:55,363 INFO [Thread-11] dao.ExecutableDao:65 : Using
> metadata url: kylin_metadata@hbase
> 2016-05-03 15:01:55,393 INFO [localhost-startStop-1]
> badquery.BadQueryHistoryManager:42 : Initializing BadQueryHistoryManager
> with config kylin_metadata@hbase
> 2016-05-03 15:01:55,950 INFO [localhost-startStop-1]
> web.DefaultSecurityFilterChain:28 : Creating filter chain:
> org.springframework.security.web.util.AnyRequestMatcher@1,
>
> [org.springframework.security.web.context.SecurityContextPersistenceFilter@4a349ef9
> ,
>
> org.springframework.security.web.authentication.logout.LogoutFilter@44b1137e
> ,
>
> org.springframework.security.web.authentication.UsernamePasswordAuthenticationFilter@4576d86d
> ,
>
> org.springframework.security.web.authentication.ui.DefaultLoginPageGeneratingFilter@7d7affbb
> ,
>
> org.springframework.security.web.authentication.www.BasicAuthenticationFilter@162b8e59
> ,
>
> org.springframework.security.web.savedrequest.RequestCacheAwareFilter@58e806e
> ,
>
> org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter@4e91b89b
> ,
>
> org.springframework.security.web.authentication.AnonymousAuthenticationFilter@40eef069
> ,
> org.springframework.security.web.session.SessionManagementFilter@6e99ecd,
> org.springframework.security.web.access.ExceptionTranslationFilter@53b8a846
> ,
>
> org.springframework.security.web.access.intercept.FilterSecurityInterceptor@2fb69b
> ]
> 2016-05-03 15:02:05,485 INFO [pool-5-thread-1]
> threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running,
> 0 ready, 6 others
> 2016-05-03 15:02:39,492 DEBUG [http-bio-7070-exec-9]
> service.AdminService:90 : Get Kylin Runtime Config
> 2016-05-03 15:02:41,280 DEBUG [http-bio-7070-exec-1]
> 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 15:02:41,369 DEBUG [http-bio-7070-exec-3]
> 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 15:02:41,515 INFO [http-bio-7070-exec-3]
> project.ProjectManager:88 : Initializing ProjectManager with metadata url
> kylin_metadata@hbase
> 2016-05-03 15:02:41,529 DEBUG [http-bio-7070-exec-3]
> project.ProjectManager:104 : Loading Project from folder
> kylin_metadata(key='/project')@kylin_metadata@hbase
> 2016-05-03 15:02:41,540 DEBUG [http-bio-7070-exec-3]
> project.ProjectManager:109 : Loaded 2 Project(s)
> 2016-05-03 15:02:41,540 DEBUG [http-bio-7070-exec-3]
> controller.ProjectController:97 : 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 15:02:41,731 INFO [http-bio-7070-exec-9]
> kafka.KafkaConfigManager:204 : Reloading Kafka Metadata from folder
> kylin_metadata(key='/kafka')@kylin_metadata@hbase
> 2016-05-03 15:02:41,735 DEBUG [http-bio-7070-exec-9]
> kafka.KafkaConfigManager:229 : Loaded 0 KafkaConfig(s)
> 2016-05-03 15:02:41,743 INFO [http-bio-7070-exec-10]
> streaming.StreamingManager:218 : Reloading Streaming Metadata from folder
> kylin_metadata(key='/streaming')@kylin_metadata@hbase
> 2016-05-03 15:02:41,751 DEBUG [http-bio-7070-exec-10]
> streaming.StreamingManager:243 : Loaded 0 StreamingConfig(s)
> 2016-05-03 15:02:49,864 INFO [http-bio-7070-exec-10]
> realization.RealizationRegistry:75 : Initializing RealizationRegistry with
> metadata url kylin_metadata@hbase
> 2016-05-03 15:02:49,864 INFO [http-bio-7070-exec-1]
> project.ProjectL2Cache:172 : Loading L2 project cache for Tutorial
> 2016-05-03 15:02:50,239 INFO [http-bio-7070-exec-10]
> reflections.Reflections:224 : Reflections took 358 ms to scan 18 urls,
> producing 274 keys and 2131 values
> 2016-05-03 15:02:50,243 INFO [http-bio-7070-exec-10]
> hybrid.HybridManager:82 : Initializing HybridManager with config
> kylin_metadata@hbase
> 2016-05-03 15:02:50,248 DEBUG [http-bio-7070-exec-10]
> hybrid.HybridManager:92 : Loading Hybrid from folder
> kylin_metadata(key='/hybrid')@kylin_metadata@hbase
> 2016-05-03 15:02:50,248 DEBUG [http-bio-7070-exec-10]
> hybrid.HybridManager:98 : Loaded 0 Hybrid(s)
> 2016-05-03 15:02:50,250 INFO [http-bio-7070-exec-10]
> invertedindex.IIManager:101 : Initializing IIManager with config
> kylin_metadata@hbase
> 2016-05-03 15:02:50,255 DEBUG [http-bio-7070-exec-10]
> invertedindex.IIManager:226 : Loading II from folder
> kylin_metadata(key='/invertedindex')@kylin_metadata@hbase
> 2016-05-03 15:02:50,255 DEBUG [http-bio-7070-exec-10]
> invertedindex.IIManager:232 : Loaded 0 II(s)
> 2016-05-03 15:02:50,258 INFO [http-bio-7070-exec-10]
> realization.RealizationRegistry:103 : RealizationRegistry is
> {CUBE=org.apache.kylin.cube.CubeManager@46c95a9d,
> HYBRID=org.apache.kylin.storage.hybrid.HybridManager@4d5c1db9,
> INVERTED_INDEX=org.apache.kylin.invertedindex.IIManager@5aa3845c}
> 2016-05-03 15:02:50,260 INFO [http-bio-7070-exec-1]
> controller.TableController:89 : Return all table metadata in 396 seconds
> 2016-05-03 15:02:54,910 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 15:02:55,053 DEBUG [http-bio-7070-exec-10]
> service.CacheService:144 : Creating a new data source, OLAP data source
> pointing to kylin_metadata@hbase
> 2016-05-03 15:02:55,055 INFO [http-bio-7070-exec-10]
> project.ProjectL2Cache:172 : Loading L2 project cache for TUTORIAL
> 2016-05-03 15:02:55,060 INFO [http-bio-7070-exec-10]
> schema.OLAPSchemaFactory:135 : Schema json:{
> "version": "1.0",
> "defaultSchema": "DEFAULT",
> "schemas": [
> {
> "type": "custom",
> "name": "DEFAULT",
> "factory": "org.apache.kylin.query.schema.OLAPSchemaFactory",
> "operand": {
> "project": "TUTORIAL"
> },
> "functions": [
> {
> name: 'MASSIN',
> className: 'org.apache.kylin.query.udf.MassInUDF'
> }
> ]
> }
> ]
> }
> 2016-05-03 15:02:55,061 DEBUG [http-bio-7070-exec-10]
> 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": "TUTORIAL"
> },
> "functions": [
> {
> name: 'MASSIN',
> className: 'org.apache.kylin.query.udf.MassInUDF'
> }
> ]
> }
> ]
> }
>
> 2016-05-03 15:02:55,368 DEBUG [http-bio-7070-exec-10]
> service.QueryService:289 : getting table metas
> 2016-05-03 15:02:55,416 DEBUG [http-bio-7070-exec-10]
> service.QueryService:307 : getting column metas
> 2016-05-03 15:02:55,454 DEBUG [http-bio-7070-exec-10]
> service.QueryService:321 : done column metas
> 2016-05-03 15:03:05,474 INFO [pool-5-thread-1]
> threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running,
> 0 ready, 6 others
> 2016-05-03 15:03:20,325 INFO [http-bio-7070-exec-5]
> controller.QueryController:174 : Using project: Tutorial
> 2016-05-03 15:03:20,326 INFO [http-bio-7070-exec-5]
> 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 LIMIT 50000
> 2016-05-03 15:03:20,825 INFO [http-bio-7070-exec-5] routing.QueryRouter:48
> : The project manager's reference is
> org.apache.kylin.metadata.project.ProjectManager@972713a
> 2016-05-03 15:03:20,826 INFO [http-bio-7070-exec-5] routing.QueryRouter:60
> : Find candidates by table DEFAULT.KYLIN_SALES and project=TUTORIAL :
> org.apache.kylin.query.routing.Candidate@6ab02f87
> 2016-05-03 15:03:20,828 INFO [http-bio-7070-exec-5] routing.QueryRouter:49
> : Applying rule: class
> org.apache.kylin.query.routing.rules.RemoveUncapableRealizationsRule,
> realizations before: [Tutorial_Cube(CUBE)], realizations after:
> [Tutorial_Cube(CUBE)]
> 2016-05-03 15:03:20,829 INFO [http-bio-7070-exec-5] routing.QueryRouter:49
> : Applying rule: class
> org.apache.kylin.query.routing.rules.RealizationSortRule, realizations
> before: [Tutorial_Cube(CUBE)], realizations after: [Tutorial_Cube(CUBE)]
> 2016-05-03 15:03:20,829 INFO [http-bio-7070-exec-5] routing.QueryRouter:72
> : The realizations remaining: [Tutorial_Cube(CUBE)] And the final chosen
> one is the first one
> 2016-05-03 15:03:21,076 DEBUG [http-bio-7070-exec-5]
> enumerator.OLAPEnumerator:107 : query storage...
> 2016-05-03 15:03:21,090 INFO [http-bio-7070-exec-5]
> v2.CubeStorageQuery:251 : exactAggregation is true
> 2016-05-03 15:03:21,093 INFO [http-bio-7070-exec-5]
> v2.CubeStorageQuery:357 : Memory budget is set to: 784134
> 2016-05-03 15:03:21,105 INFO [http-bio-7070-exec-5]
> dict.DictionaryManager:385 : DictionaryManager(91588327) loading
> DictionaryInfo(loadDictObj:true) at
> /dict/DEFAULT.KYLIN_CAL_DT/CAL_DT/7f549503-b7f8-4ac9-b826-0f2ceaed1304.dict
> 2016-05-03 15:03:21,134 DEBUG [http-bio-7070-exec-5]
> v2.CubeHBaseEndpointRPC:256 : New scanner for current segment
> Tutorial_Cube[20111230000000_20160430000000] will use
> SCAN_FILTER_AGGR_CHECKMEM as endpoint's behavior
> 2016-05-03 15:03:21,148 DEBUG [http-bio-7070-exec-5]
> v2.CubeHBaseEndpointRPC:291 : Serialized scanRequestBytes 320 bytes,
> rawScanBytesString 46 bytes
> 2016-05-03 15:03:21,148 INFO [http-bio-7070-exec-5]
> 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 15:03:21,150 INFO [http-bio-7070-exec-5] v2.CubeHBaseRPC:309 :
> Visiting hbase table KYLIN_3UZ4ZT17UO: cuboid exact match, from 8 to 8
> Start: \x00\x00\x00\x00\x00\x00\x00\x00\x00\x08\x00\x00\x00
> (\x00\x00\x00\x00\x00\x00\x00\x00\x00\x08\x00\x00\x00) Stop:
> \x00\x00\x00\x00\x00\x00\x00\x00\x00\x08\xFF\xFF\xFF\x00
> (\x00\x00\x00\x00\x00\x00\x00\x00\x00\x08\xFF\xFF\xFF\x00), No Fuzzy Key
> 2016-05-03 15:03:21,150 DEBUG [http-bio-7070-exec-5]
> v2.CubeHBaseEndpointRPC:300 : Submitting rpc to 1 shards starting from
> shard 1, scan requests count 1
> 2016-05-03 15:03:21,220 INFO [http-bio-7070-exec-5]
> v2.CubeHBaseEndpointRPC:125 : Timeout for ExpectedSizeIterator is 60000
> 2016-05-03 15:03:21,227 DEBUG [http-bio-7070-exec-5]
> enumerator.OLAPEnumerator:127 : return TupleIterator...
> 2016-05-03 15:03:21,284 ERROR [pool-7-thread-1] v2.CubeHBaseEndpointRPC:98
> : Execution exception when running task in pool-7-thread-1
> 2016-05-03 15:03:21,285 ERROR [pool-7-thread-1] v2.CubeHBaseEndpointRPC:108
> : Caught exception in thread pool-7-thread-1:
> java.lang.RuntimeException: Error when visiting cubes by endpoint:
> at
>
> org.apache.kylin.storage.hbase.cube.v2.CubeHBaseEndpointRPC$1.run(CubeHBaseEndpointRPC.java:323)
> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
> at java.util.concurrent.FutureTask.run(FutureTask.java:262)
> at
>
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
> at
>
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
> at java.lang.Thread.run(Thread.java:745)
> Caused by: org.apache.hadoop.hbase.DoNotRetryIOException:
> org.apache.hadoop.hbase.DoNotRetryIOException: java.lang.NoSuchMethodError:
>
> org.apache.hadoop.hbase.coprocessor.RegionCoprocessorEnvironment.getRegion()Lorg/apache/hadoop/hbase/regionserver/HRegion;
> at org.apache.hadoop.hbase.ipc.RpcServer.call(RpcServer.java:2212)
> at org.apache.hadoop.hbase.ipc.CallRunner.run(CallRunner.java:109)
> at
> org.apache.hadoop.hbase.ipc.RpcExecutor.consumerLoop(RpcExecutor.java:133)
> at org.apache.hadoop.hbase.ipc.RpcExecutor$1.run(RpcExecutor.java:108)
> at java.lang.Thread.run(Thread.java:745)
> Caused by: java.lang.NoSuchMethodError:
>
> org.apache.hadoop.hbase.coprocessor.RegionCoprocessorEnvironment.getRegion()Lorg/apache/hadoop/hbase/regionserver/HRegion;
> at
>
> org.apache.kylin.storage.hbase.cube.v2.coprocessor.endpoint.CubeVisitService.visitCube(CubeVisitService.java:158)
> at
>
> org.apache.kylin.storage.hbase.cube.v2.coprocessor.endpoint.generated.CubeVisitProtos$CubeVisitService.callMethod(CubeVisitProtos.java:3800)
> at
> org.apache.hadoop.hbase.regionserver.HRegion.execService(HRegion.java:7789)
> at
>
> org.apache.hadoop.hbase.regionserver.RSRpcServices.execServiceOnRegion(RSRpcServices.java:1988)
> at
>
> org.apache.hadoop.hbase.regionserver.RSRpcServices.execService(RSRpcServices.java:1970)
> at
>
> org.apache.hadoop.hbase.protobuf.generated.ClientProtos$ClientService$2.callBlockingMethod(ClientProtos.java:33652)
> at org.apache.hadoop.hbase.ipc.RpcServer.call(RpcServer.java:2170)
> ... 4 more
>
> at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
> at
>
> sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
> at
>
> sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
> at java.lang.reflect.Constructor.newInstance(Constructor.java:526)
> at
>
> org.apache.hadoop.ipc.RemoteException.instantiateException(RemoteException.java:106)
> at
>
> org.apache.hadoop.ipc.RemoteException.unwrapRemoteException(RemoteException.java:95)
> at
>
> org.apache.hadoop.hbase.protobuf.ProtobufUtil.getRemoteException(ProtobufUtil.java:327)
> at
>
> org.apache.hadoop.hbase.protobuf.ProtobufUtil.execService(ProtobufUtil.java:1624)
> at
>
> org.apache.hadoop.hbase.ipc.RegionCoprocessorRpcChannel$1.call(RegionCoprocessorRpcChannel.java:92)
> at
>
> org.apache.hadoop.hbase.ipc.RegionCoprocessorRpcChannel$1.call(RegionCoprocessorRpcChannel.java:89)
> at
>
> org.apache.hadoop.hbase.client.RpcRetryingCaller.callWithRetries(RpcRetryingCaller.java:126)
> at
>
> org.apache.hadoop.hbase.ipc.RegionCoprocessorRpcChannel.callExecService(RegionCoprocessorRpcChannel.java:95)
> at
>
> org.apache.hadoop.hbase.ipc.CoprocessorRpcChannel.callMethod(CoprocessorRpcChannel.java:56)
> at
>
> org.apache.kylin.storage.hbase.cube.v2.coprocessor.endpoint.generated.CubeVisitProtos$CubeVisitService$Stub.visitCube(CubeVisitProtos.java:3861)
> at
>
> org.apache.kylin.storage.hbase.cube.v2.CubeHBaseEndpointRPC$2.call(CubeHBaseEndpointRPC.java:361)
> at
>
> org.apache.kylin.storage.hbase.cube.v2.CubeHBaseEndpointRPC$2.call(CubeHBaseEndpointRPC.java:357)
> at org.apache.hadoop.hbase.client.HTable$15.call(HTable.java:1797)
> ... 4 more
> Caused by:
>
> org.apache.hadoop.hbase.ipc.RemoteWithExtrasException(org.apache.hadoop.hbase.DoNotRetryIOException):
> org.apache.hadoop.hbase.DoNotRetryIOException: java.lang.NoSuchMethodError:
>
> org.apache.hadoop.hbase.coprocessor.RegionCoprocessorEnvironment.getRegion()Lorg/apache/hadoop/hbase/regionserver/HRegion;
> at org.apache.hadoop.hbase.ipc.RpcServer.call(RpcServer.java:2212)
> at org.apache.hadoop.hbase.ipc.CallRunner.run(CallRunner.java:109)
> at
> org.apache.hadoop.hbase.ipc.RpcExecutor.consumerLoop(RpcExecutor.java:133)
> at org.apache.hadoop.hbase.ipc.RpcExecutor$1.run(RpcExecutor.java:108)
> at java.lang.Thread.run(Thread.java:745)
> Caused by: java.lang.NoSuchMethodError:
>
> org.apache.hadoop.hbase.coprocessor.RegionCoprocessorEnvironment.getRegion()Lorg/apache/hadoop/hbase/regionserver/HRegion;
> at
>
> org.apache.kylin.storage.hbase.cube.v2.coprocessor.endpoint.CubeVisitService.visitCube(CubeVisitService.java:158)
> at
>
> org.apache.kylin.storage.hbase.cube.v2.coprocessor.endpoint.generated.CubeVisitProtos$CubeVisitService.callMethod(CubeVisitProtos.java:3800)
> at
> org.apache.hadoop.hbase.regionserver.HRegion.execService(HRegion.java:7789)
> at
>
> org.apache.hadoop.hbase.regionserver.RSRpcServices.execServiceOnRegion(RSRpcServices.java:1988)
> at
>
> org.apache.hadoop.hbase.regionserver.RSRpcServices.execService(RSRpcServices.java:1970)
> at
>
> org.apache.hadoop.hbase.protobuf.generated.ClientProtos$ClientService$2.callBlockingMethod(ClientProtos.java:33652)
> at org.apache.hadoop.hbase.ipc.RpcServer.call(RpcServer.java:2170)
> ... 4 more
>
> at org.apache.hadoop.hbase.ipc.RpcClientImpl.call(RpcClientImpl.java:1268)
> at
>
> org.apache.hadoop.hbase.ipc.AbstractRpcClient.callBlockingMethod(AbstractRpcClient.java:226)
> at
>
> org.apache.hadoop.hbase.ipc.AbstractRpcClient$BlockingRpcChannelImplementation.callBlockingMethod(AbstractRpcClient.java:331)
> at
>
> org.apache.hadoop.hbase.protobuf.generated.ClientProtos$ClientService$BlockingStub.execService(ClientProtos.java:34118)
> at
>
> org.apache.hadoop.hbase.protobuf.ProtobufUtil.execService(ProtobufUtil.java:1620)
> ... 13 more
> 2016-05-03 15:04:05,474 INFO [pool-5-thread-1]
> threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running,
> 0 ready, 6 others
> 2016-05-03 15:04:21,229 ERROR [http-bio-7070-exec-5]
> 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$$f73318de.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:57)
> at
>
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:606)
> 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:201)
> 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:1145)
> at
>
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
> 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 15:04:21,233 INFO [http-bio-7070-exec-5]
> 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 LIMIT 50000
> User: ADMIN
> Success: false
> Duration: 0.0
> Project: Tutorial
> Realization Names: [Tutorial_Cube]
> Cuboid Ids: [8]
> 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 15:04:21,237 ERROR [http-bio-7070-exec-5]
> 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:57)
> at
>
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:606)
> 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:201)
> 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:1145)
> at
>
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
> at
>
> org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
> at java.lang.Thread.run(Thread.java:745)
>
>
> ===============================================================================================================================================
>
> On Tue, May 3, 2016 at 9:56 PM, hongbin ma <ma...@apache.org> wrote:
>
> > Hi Amit
> >
> > seems no attachment is found
> >
> > On Tue, May 3, 2016 at 11:09 PM, Amit Jain <am...@gmail.com> wrote:
> >
> > > Hi Li,
> > > Log file is attached. Please let me know your findings.
> > > Thanks for your help.
> > > Amit
> > >
> > > On Tue, May 3, 2016 at 5:00 AM, Li Yang <li...@apache.org> wrote:
> > >
> > >> Could you share kylin.log? There must be something else behind the
> > timeout
> > >> error.
> > >>
> > >> On Fri, Apr 29, 2016 at 11:53 AM, Amit Jain <am...@gmail.com> wrote:
> > >>
> > >> > I'm getting following error when running the query as described on
> > >> > http://kylin.apache.org/docs15/tutorial/kylin_sample.html
> > >> > I'm get to the step where the cube build succeeds but when I run the
> > >> query
> > >> > on Insight, I get the error.
> > >> >
> > >> > 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!
> > >> >
> > >> > Please note that I'm running this on a 5 Node Hadoop Cluster with
> > >> CDH5.7 on
> > >> > AWS. I tried installing Kylin on datanode first and got this error.
> > >> Later I
> > >> > installed Kylin on namenode and still getting the same error
> message.
> > >> > I can make Kylin work on CDH VM with single node but not on a Hadoop
> > >> > multinode cluster.
> > >> > Please let me know how to fix this.
> > >> > Thanks
> > >> > Amit
> > >> >
> > >>
> > >
> > >
> >
> >
> > --
> > Regards,
> >
> > *Bin Mahone | 马洪宾*
> > Apache Kylin: http://kylin.io
> > Github: https://github.com/binmahone
> >
>
--
Regards,
*Bin Mahone | 马洪宾*
Apache Kylin: http://kylin.io
Github: https://github.com/binmahone
Re: Timeout visiting cube!
Posted by Amit Jain <am...@gmail.com>.
Here is the text from kylin.log
===============================================================================================================================================
2016-05-03 15:01:51,151 INFO [localhost-startStop-1]
core.SpringSecurityCoreVersion:33 : You are running with Spring Security
Core 3.1.2.RELEASE
2016-05-03 15:01:51,153 INFO [localhost-startStop-1]
config.SecurityNamespaceHandler:59 : Spring Security 'config' module
version is 3.1.2.RELEASE
2016-05-03 15:01:51,168 INFO [localhost-startStop-1]
method.GlobalMethodSecurityBeanDefinitionParser:141 : Using bean
'expressionHandler' as method ExpressionHandler implementation
2016-05-03 15:01:51,208 INFO [localhost-startStop-1]
http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
control expression attribute 'permitAll' for /api/user/authentication*/**
2016-05-03 15:01:51,208 INFO [localhost-startStop-1]
http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
control expression attribute 'isAuthenticated()' for /api/query*/**
2016-05-03 15:01:51,208 INFO [localhost-startStop-1]
http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
control expression attribute 'isAuthenticated()' for /api/metadata*/**
2016-05-03 15:01:51,208 INFO [localhost-startStop-1]
http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
control expression attribute 'permitAll' for /api/**/metrics
2016-05-03 15:01:51,209 INFO [localhost-startStop-1]
http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
control expression attribute 'permitAll' for /api/cache*/**
2016-05-03 15:01:51,209 INFO [localhost-startStop-1]
http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
control expression attribute 'hasAnyRole('ROLE_ANALYST')' for
/api/cubes/src/tables
2016-05-03 15:01:51,209 INFO [localhost-startStop-1]
http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
control expression attribute 'isAuthenticated()' for /api/cubes*/**
2016-05-03 15:01:51,209 INFO [localhost-startStop-1]
http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
control expression attribute 'isAuthenticated()' for /api/models*/**
2016-05-03 15:01:51,209 INFO [localhost-startStop-1]
http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
control expression attribute 'isAuthenticated()' for /api/streaming*/**
2016-05-03 15:01:51,209 INFO [localhost-startStop-1]
http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
control expression attribute 'isAuthenticated()' for /api/job*/**
2016-05-03 15:01:51,210 INFO [localhost-startStop-1]
http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
control expression attribute 'permitAll' for /api/admin/config
2016-05-03 15:01:51,210 INFO [localhost-startStop-1]
http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
control expression attribute 'permitAll' for /api/projects
2016-05-03 15:01:51,210 INFO [localhost-startStop-1]
http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
control expression attribute 'hasRole('ROLE_ADMIN')' for /api/admin*/**
2016-05-03 15:01:51,211 INFO [localhost-startStop-1]
http.FilterInvocationSecurityMetadataSourceParser:134 : Creating access
control expression attribute 'isAuthenticated()' for /api/**
2016-05-03 15:01:51,225 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.
2016-05-03 15:01:51,229 INFO [localhost-startStop-1]
http.HttpSecurityBeanDefinitionParser:264 : Checking sorted filter chain:
[Root bean: class
[org.springframework.security.web.context.SecurityContextPersistenceFilter];
scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0;
autowireCandidate=true; primary=false; factoryBeanName=null;
factoryMethodName=null; initMethodName=null; destroyMethodName=null, order
= 300, Root bean: class
[org.springframework.security.web.authentication.logout.LogoutFilter];
scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0;
autowireCandidate=true; primary=false; factoryBeanName=null;
factoryMethodName=null; initMethodName=null; destroyMethodName=null, order
= 400,
<org.springframework.security.web.authentication.UsernamePasswordAuthenticationFilter#0>,
order = 800, Root bean: class
[org.springframework.security.web.authentication.ui.DefaultLoginPageGeneratingFilter];
scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0;
autowireCandidate=true; primary=false; factoryBeanName=null;
factoryMethodName=null; initMethodName=null; destroyMethodName=null, order
= 1000, Root bean: class
[org.springframework.security.web.authentication.www.BasicAuthenticationFilter];
scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0;
autowireCandidate=true; primary=false; factoryBeanName=null;
factoryMethodName=null; initMethodName=null; destroyMethodName=null, order
= 1200, Root bean: class
[org.springframework.security.web.savedrequest.RequestCacheAwareFilter];
scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0;
autowireCandidate=true; primary=false; factoryBeanName=null;
factoryMethodName=null; initMethodName=null; destroyMethodName=null, order
= 1300, Root bean: class
[org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter];
scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0;
autowireCandidate=true; primary=false; factoryBeanName=null;
factoryMethodName=null; initMethodName=null; destroyMethodName=null, order
= 1400, Root bean: class
[org.springframework.security.web.authentication.AnonymousAuthenticationFilter];
scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0;
autowireCandidate=true; primary=false; factoryBeanName=null;
factoryMethodName=null; initMethodName=null; destroyMethodName=null, order
= 1700, Root bean: class
[org.springframework.security.web.session.SessionManagementFilter]; scope=;
abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0;
autowireCandidate=true; primary=false; factoryBeanName=null;
factoryMethodName=null; initMethodName=null; destroyMethodName=null, order
= 1800, Root bean: class
[org.springframework.security.web.access.ExceptionTranslationFilter];
scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0;
autowireCandidate=true; primary=false; factoryBeanName=null;
factoryMethodName=null; initMethodName=null; destroyMethodName=null, order
= 1900,
<org.springframework.security.web.access.intercept.FilterSecurityInterceptor#0>,
order = 2000]
2016-05-03 15:01:51,418 WARN [localhost-startStop-1]
common.KylinConfig:162 : KYLIN_CONF property was not set, will seek
KYLIN_HOME env variable
2016-05-03 15:01:51,456 INFO [localhost-startStop-1]
security.PasswordPlaceholderConfigurer:177 : Loading properties file from
resource loaded through InputStream
2016-05-03 15:01:51,833 INFO [localhost-startStop-1] util.Version:27 :
HV000001: Hibernate Validator 5.1.3.Final
2016-05-03 15:01:52,383 INFO [localhost-startStop-1]
init.InitialTaskManager:36 : Kylin service is starting.....
2016-05-03 15:01:52,909 INFO [localhost-startStop-1]
filter.AnnotationSizeOfFilter:53 : Using regular expression provided
through VM argument net.sf.ehcache.pool.sizeof.ignore.pattern for
IgnoreSizeOf annotation : ^.*cache\..*IgnoreSizeOf$
2016-05-03 15:01:52,919 INFO [localhost-startStop-1]
sizeof.JvmInformation:545 : Detected JVM data model settings of: 64-Bit
HotSpot JVM with Compressed OOPs and Concurrent Mark-and-Sweep GC
2016-05-03 15:01:53,151 INFO [localhost-startStop-1]
sizeof.AgentLoader:197 : Extracted agent jar to temporary file
bin/../tomcat/temp/ehcache-sizeof-agent6516450038826818696.jar
2016-05-03 15:01:53,152 INFO [localhost-startStop-1]
sizeof.AgentLoader:137 : Trying to load agent @
bin/../tomcat/temp/ehcache-sizeof-agent6516450038826818696.jar
2016-05-03 15:01:53,157 INFO [localhost-startStop-1]
impl.DefaultSizeOfEngine:113 : using Agent sizeof engine
2016-05-03 15:01:53,319 INFO [localhost-startStop-1] cube.CubeManager:124
: Initializing CubeManager with config kylin_metadata@hbase
2016-05-03 15:01:53,322 INFO [localhost-startStop-1]
persistence.ResourceStore:82 : Using metadata url kylin_metadata@hbase for
resource store
2016-05-03 15:01:53,470 INFO [localhost-startStop-1]
hbase.HBaseConnection:139 : connection is null or closed, creating a new one
2016-05-03 15:01:54,927 DEBUG [localhost-startStop-1]
hbase.HBaseConnection:185 : HTable 'kylin_metadata' already exists
2016-05-03 15:01:54,979 DEBUG [localhost-startStop-1] cube.CubeManager:811
: Loading Cube from folder kylin_metadata(key='/cube')@kylin_metadata@hbase
2016-05-03 15:01:55,064 INFO [localhost-startStop-1]
cube.CubeDescManager:91 : Initializing CubeDescManager with config
kylin_metadata@hbase
2016-05-03 15:01:55,064 INFO [localhost-startStop-1]
cube.CubeDescManager:197 : Reloading Cube Metadata from folder
kylin_metadata(key='/cube_desc')@kylin_metadata@hbase
2016-05-03 15:01:55,112 DEBUG [localhost-startStop-1]
metadata.MetadataManager:315 : Reloading SourceTable from folder
kylin_metadata(key='/table')@kylin_metadata@hbase
2016-05-03 15:01:55,159 DEBUG [localhost-startStop-1]
metadata.MetadataManager:324 : Loaded 3 SourceTable(s)
2016-05-03 15:01:55,159 DEBUG [localhost-startStop-1]
metadata.MetadataManager:256 : Reloading SourceTable exd info from folder
kylin_metadata(key='/table_exd')@kylin_metadata@hbase
2016-05-03 15:01:55,196 DEBUG [localhost-startStop-1]
metadata.MetadataManager:265 : Loaded 2 SourceTable EXD(s)
2016-05-03 15:01:55,197 DEBUG [localhost-startStop-1]
metadata.MetadataManager:429 : Reloading DataModel from folder
kylin_metadata(key='/model_desc')@kylin_metadata@hbase
2016-05-03 15:01:55,224 DEBUG [localhost-startStop-1]
metadata.MetadataManager:443 : Loaded 2 DataModel(s)
2016-05-03 15:01:55,225 DEBUG [localhost-startStop-1]
metadata.MetadataManager:301 : Reloading ExternalFilter from folder
kylin_metadata(key='/ext_filter')@kylin_metadata@hbase
2016-05-03 15:01:55,229 DEBUG [localhost-startStop-1]
metadata.MetadataManager:310 : Loaded 0 SourceTable(s)
2016-05-03 15:01:55,246 DEBUG [localhost-startStop-1]
cube.CubeDescManager:222 : Loaded 2 Cube(s)
2016-05-03 15:01:55,256 DEBUG [localhost-startStop-1] cube.CubeManager:846
: Reloaded new cube: Tutorial_Cube with reference
beingCUBE[name=Tutorial_Cube] having 1 segments:KYLIN_3UZ4ZT17UO
2016-05-03 15:01:55,259 DEBUG [localhost-startStop-1] cube.CubeManager:846
: Reloaded new cube: kylin_sales_cube with reference
beingCUBE[name=kylin_sales_cube] having 2
segments:KYLIN_K7Z527EQEX,KYLIN_ENZP3OPKTE
2016-05-03 15:01:55,259 DEBUG [localhost-startStop-1] cube.CubeManager:817
: Loaded 2 Cube(s)
2016-05-03 15:01:55,353 INFO [localhost-startStop-1]
controller.JobController:83 : Initializing Job Engine ....
2016-05-03 15:01:55,358 INFO [Thread-11] manager.ExecutableManager:73 :
Using metadata url: kylin_metadata@hbase
2016-05-03 15:01:55,363 INFO [Thread-11] dao.ExecutableDao:65 : Using
metadata url: kylin_metadata@hbase
2016-05-03 15:01:55,393 INFO [localhost-startStop-1]
badquery.BadQueryHistoryManager:42 : Initializing BadQueryHistoryManager
with config kylin_metadata@hbase
2016-05-03 15:01:55,950 INFO [localhost-startStop-1]
web.DefaultSecurityFilterChain:28 : Creating filter chain:
org.springframework.security.web.util.AnyRequestMatcher@1,
[org.springframework.security.web.context.SecurityContextPersistenceFilter@4a349ef9,
org.springframework.security.web.authentication.logout.LogoutFilter@44b1137e,
org.springframework.security.web.authentication.UsernamePasswordAuthenticationFilter@4576d86d,
org.springframework.security.web.authentication.ui.DefaultLoginPageGeneratingFilter@7d7affbb,
org.springframework.security.web.authentication.www.BasicAuthenticationFilter@162b8e59,
org.springframework.security.web.savedrequest.RequestCacheAwareFilter@58e806e,
org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter@4e91b89b,
org.springframework.security.web.authentication.AnonymousAuthenticationFilter@40eef069,
org.springframework.security.web.session.SessionManagementFilter@6e99ecd,
org.springframework.security.web.access.ExceptionTranslationFilter@53b8a846,
org.springframework.security.web.access.intercept.FilterSecurityInterceptor@2fb69b
]
2016-05-03 15:02:05,485 INFO [pool-5-thread-1]
threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running,
0 ready, 6 others
2016-05-03 15:02:39,492 DEBUG [http-bio-7070-exec-9]
service.AdminService:90 : Get Kylin Runtime Config
2016-05-03 15:02:41,280 DEBUG [http-bio-7070-exec-1]
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 15:02:41,369 DEBUG [http-bio-7070-exec-3]
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 15:02:41,515 INFO [http-bio-7070-exec-3]
project.ProjectManager:88 : Initializing ProjectManager with metadata url
kylin_metadata@hbase
2016-05-03 15:02:41,529 DEBUG [http-bio-7070-exec-3]
project.ProjectManager:104 : Loading Project from folder
kylin_metadata(key='/project')@kylin_metadata@hbase
2016-05-03 15:02:41,540 DEBUG [http-bio-7070-exec-3]
project.ProjectManager:109 : Loaded 2 Project(s)
2016-05-03 15:02:41,540 DEBUG [http-bio-7070-exec-3]
controller.ProjectController:97 : 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 15:02:41,731 INFO [http-bio-7070-exec-9]
kafka.KafkaConfigManager:204 : Reloading Kafka Metadata from folder
kylin_metadata(key='/kafka')@kylin_metadata@hbase
2016-05-03 15:02:41,735 DEBUG [http-bio-7070-exec-9]
kafka.KafkaConfigManager:229 : Loaded 0 KafkaConfig(s)
2016-05-03 15:02:41,743 INFO [http-bio-7070-exec-10]
streaming.StreamingManager:218 : Reloading Streaming Metadata from folder
kylin_metadata(key='/streaming')@kylin_metadata@hbase
2016-05-03 15:02:41,751 DEBUG [http-bio-7070-exec-10]
streaming.StreamingManager:243 : Loaded 0 StreamingConfig(s)
2016-05-03 15:02:49,864 INFO [http-bio-7070-exec-10]
realization.RealizationRegistry:75 : Initializing RealizationRegistry with
metadata url kylin_metadata@hbase
2016-05-03 15:02:49,864 INFO [http-bio-7070-exec-1]
project.ProjectL2Cache:172 : Loading L2 project cache for Tutorial
2016-05-03 15:02:50,239 INFO [http-bio-7070-exec-10]
reflections.Reflections:224 : Reflections took 358 ms to scan 18 urls,
producing 274 keys and 2131 values
2016-05-03 15:02:50,243 INFO [http-bio-7070-exec-10]
hybrid.HybridManager:82 : Initializing HybridManager with config
kylin_metadata@hbase
2016-05-03 15:02:50,248 DEBUG [http-bio-7070-exec-10]
hybrid.HybridManager:92 : Loading Hybrid from folder
kylin_metadata(key='/hybrid')@kylin_metadata@hbase
2016-05-03 15:02:50,248 DEBUG [http-bio-7070-exec-10]
hybrid.HybridManager:98 : Loaded 0 Hybrid(s)
2016-05-03 15:02:50,250 INFO [http-bio-7070-exec-10]
invertedindex.IIManager:101 : Initializing IIManager with config
kylin_metadata@hbase
2016-05-03 15:02:50,255 DEBUG [http-bio-7070-exec-10]
invertedindex.IIManager:226 : Loading II from folder
kylin_metadata(key='/invertedindex')@kylin_metadata@hbase
2016-05-03 15:02:50,255 DEBUG [http-bio-7070-exec-10]
invertedindex.IIManager:232 : Loaded 0 II(s)
2016-05-03 15:02:50,258 INFO [http-bio-7070-exec-10]
realization.RealizationRegistry:103 : RealizationRegistry is
{CUBE=org.apache.kylin.cube.CubeManager@46c95a9d,
HYBRID=org.apache.kylin.storage.hybrid.HybridManager@4d5c1db9,
INVERTED_INDEX=org.apache.kylin.invertedindex.IIManager@5aa3845c}
2016-05-03 15:02:50,260 INFO [http-bio-7070-exec-1]
controller.TableController:89 : Return all table metadata in 396 seconds
2016-05-03 15:02:54,910 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 15:02:55,053 DEBUG [http-bio-7070-exec-10]
service.CacheService:144 : Creating a new data source, OLAP data source
pointing to kylin_metadata@hbase
2016-05-03 15:02:55,055 INFO [http-bio-7070-exec-10]
project.ProjectL2Cache:172 : Loading L2 project cache for TUTORIAL
2016-05-03 15:02:55,060 INFO [http-bio-7070-exec-10]
schema.OLAPSchemaFactory:135 : Schema json:{
"version": "1.0",
"defaultSchema": "DEFAULT",
"schemas": [
{
"type": "custom",
"name": "DEFAULT",
"factory": "org.apache.kylin.query.schema.OLAPSchemaFactory",
"operand": {
"project": "TUTORIAL"
},
"functions": [
{
name: 'MASSIN',
className: 'org.apache.kylin.query.udf.MassInUDF'
}
]
}
]
}
2016-05-03 15:02:55,061 DEBUG [http-bio-7070-exec-10]
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": "TUTORIAL"
},
"functions": [
{
name: 'MASSIN',
className: 'org.apache.kylin.query.udf.MassInUDF'
}
]
}
]
}
2016-05-03 15:02:55,368 DEBUG [http-bio-7070-exec-10]
service.QueryService:289 : getting table metas
2016-05-03 15:02:55,416 DEBUG [http-bio-7070-exec-10]
service.QueryService:307 : getting column metas
2016-05-03 15:02:55,454 DEBUG [http-bio-7070-exec-10]
service.QueryService:321 : done column metas
2016-05-03 15:03:05,474 INFO [pool-5-thread-1]
threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running,
0 ready, 6 others
2016-05-03 15:03:20,325 INFO [http-bio-7070-exec-5]
controller.QueryController:174 : Using project: Tutorial
2016-05-03 15:03:20,326 INFO [http-bio-7070-exec-5]
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 LIMIT 50000
2016-05-03 15:03:20,825 INFO [http-bio-7070-exec-5] routing.QueryRouter:48
: The project manager's reference is
org.apache.kylin.metadata.project.ProjectManager@972713a
2016-05-03 15:03:20,826 INFO [http-bio-7070-exec-5] routing.QueryRouter:60
: Find candidates by table DEFAULT.KYLIN_SALES and project=TUTORIAL :
org.apache.kylin.query.routing.Candidate@6ab02f87
2016-05-03 15:03:20,828 INFO [http-bio-7070-exec-5] routing.QueryRouter:49
: Applying rule: class
org.apache.kylin.query.routing.rules.RemoveUncapableRealizationsRule,
realizations before: [Tutorial_Cube(CUBE)], realizations after:
[Tutorial_Cube(CUBE)]
2016-05-03 15:03:20,829 INFO [http-bio-7070-exec-5] routing.QueryRouter:49
: Applying rule: class
org.apache.kylin.query.routing.rules.RealizationSortRule, realizations
before: [Tutorial_Cube(CUBE)], realizations after: [Tutorial_Cube(CUBE)]
2016-05-03 15:03:20,829 INFO [http-bio-7070-exec-5] routing.QueryRouter:72
: The realizations remaining: [Tutorial_Cube(CUBE)] And the final chosen
one is the first one
2016-05-03 15:03:21,076 DEBUG [http-bio-7070-exec-5]
enumerator.OLAPEnumerator:107 : query storage...
2016-05-03 15:03:21,090 INFO [http-bio-7070-exec-5]
v2.CubeStorageQuery:251 : exactAggregation is true
2016-05-03 15:03:21,093 INFO [http-bio-7070-exec-5]
v2.CubeStorageQuery:357 : Memory budget is set to: 784134
2016-05-03 15:03:21,105 INFO [http-bio-7070-exec-5]
dict.DictionaryManager:385 : DictionaryManager(91588327) loading
DictionaryInfo(loadDictObj:true) at
/dict/DEFAULT.KYLIN_CAL_DT/CAL_DT/7f549503-b7f8-4ac9-b826-0f2ceaed1304.dict
2016-05-03 15:03:21,134 DEBUG [http-bio-7070-exec-5]
v2.CubeHBaseEndpointRPC:256 : New scanner for current segment
Tutorial_Cube[20111230000000_20160430000000] will use
SCAN_FILTER_AGGR_CHECKMEM as endpoint's behavior
2016-05-03 15:03:21,148 DEBUG [http-bio-7070-exec-5]
v2.CubeHBaseEndpointRPC:291 : Serialized scanRequestBytes 320 bytes,
rawScanBytesString 46 bytes
2016-05-03 15:03:21,148 INFO [http-bio-7070-exec-5]
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 15:03:21,150 INFO [http-bio-7070-exec-5] v2.CubeHBaseRPC:309 :
Visiting hbase table KYLIN_3UZ4ZT17UO: cuboid exact match, from 8 to 8
Start: \x00\x00\x00\x00\x00\x00\x00\x00\x00\x08\x00\x00\x00
(\x00\x00\x00\x00\x00\x00\x00\x00\x00\x08\x00\x00\x00) Stop:
\x00\x00\x00\x00\x00\x00\x00\x00\x00\x08\xFF\xFF\xFF\x00
(\x00\x00\x00\x00\x00\x00\x00\x00\x00\x08\xFF\xFF\xFF\x00), No Fuzzy Key
2016-05-03 15:03:21,150 DEBUG [http-bio-7070-exec-5]
v2.CubeHBaseEndpointRPC:300 : Submitting rpc to 1 shards starting from
shard 1, scan requests count 1
2016-05-03 15:03:21,220 INFO [http-bio-7070-exec-5]
v2.CubeHBaseEndpointRPC:125 : Timeout for ExpectedSizeIterator is 60000
2016-05-03 15:03:21,227 DEBUG [http-bio-7070-exec-5]
enumerator.OLAPEnumerator:127 : return TupleIterator...
2016-05-03 15:03:21,284 ERROR [pool-7-thread-1] v2.CubeHBaseEndpointRPC:98
: Execution exception when running task in pool-7-thread-1
2016-05-03 15:03:21,285 ERROR [pool-7-thread-1] v2.CubeHBaseEndpointRPC:108
: Caught exception in thread pool-7-thread-1:
java.lang.RuntimeException: Error when visiting cubes by endpoint:
at
org.apache.kylin.storage.hbase.cube.v2.CubeHBaseEndpointRPC$1.run(CubeHBaseEndpointRPC.java:323)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
Caused by: org.apache.hadoop.hbase.DoNotRetryIOException:
org.apache.hadoop.hbase.DoNotRetryIOException: java.lang.NoSuchMethodError:
org.apache.hadoop.hbase.coprocessor.RegionCoprocessorEnvironment.getRegion()Lorg/apache/hadoop/hbase/regionserver/HRegion;
at org.apache.hadoop.hbase.ipc.RpcServer.call(RpcServer.java:2212)
at org.apache.hadoop.hbase.ipc.CallRunner.run(CallRunner.java:109)
at
org.apache.hadoop.hbase.ipc.RpcExecutor.consumerLoop(RpcExecutor.java:133)
at org.apache.hadoop.hbase.ipc.RpcExecutor$1.run(RpcExecutor.java:108)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.lang.NoSuchMethodError:
org.apache.hadoop.hbase.coprocessor.RegionCoprocessorEnvironment.getRegion()Lorg/apache/hadoop/hbase/regionserver/HRegion;
at
org.apache.kylin.storage.hbase.cube.v2.coprocessor.endpoint.CubeVisitService.visitCube(CubeVisitService.java:158)
at
org.apache.kylin.storage.hbase.cube.v2.coprocessor.endpoint.generated.CubeVisitProtos$CubeVisitService.callMethod(CubeVisitProtos.java:3800)
at
org.apache.hadoop.hbase.regionserver.HRegion.execService(HRegion.java:7789)
at
org.apache.hadoop.hbase.regionserver.RSRpcServices.execServiceOnRegion(RSRpcServices.java:1988)
at
org.apache.hadoop.hbase.regionserver.RSRpcServices.execService(RSRpcServices.java:1970)
at
org.apache.hadoop.hbase.protobuf.generated.ClientProtos$ClientService$2.callBlockingMethod(ClientProtos.java:33652)
at org.apache.hadoop.hbase.ipc.RpcServer.call(RpcServer.java:2170)
... 4 more
at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at
sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
at
sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:526)
at
org.apache.hadoop.ipc.RemoteException.instantiateException(RemoteException.java:106)
at
org.apache.hadoop.ipc.RemoteException.unwrapRemoteException(RemoteException.java:95)
at
org.apache.hadoop.hbase.protobuf.ProtobufUtil.getRemoteException(ProtobufUtil.java:327)
at
org.apache.hadoop.hbase.protobuf.ProtobufUtil.execService(ProtobufUtil.java:1624)
at
org.apache.hadoop.hbase.ipc.RegionCoprocessorRpcChannel$1.call(RegionCoprocessorRpcChannel.java:92)
at
org.apache.hadoop.hbase.ipc.RegionCoprocessorRpcChannel$1.call(RegionCoprocessorRpcChannel.java:89)
at
org.apache.hadoop.hbase.client.RpcRetryingCaller.callWithRetries(RpcRetryingCaller.java:126)
at
org.apache.hadoop.hbase.ipc.RegionCoprocessorRpcChannel.callExecService(RegionCoprocessorRpcChannel.java:95)
at
org.apache.hadoop.hbase.ipc.CoprocessorRpcChannel.callMethod(CoprocessorRpcChannel.java:56)
at
org.apache.kylin.storage.hbase.cube.v2.coprocessor.endpoint.generated.CubeVisitProtos$CubeVisitService$Stub.visitCube(CubeVisitProtos.java:3861)
at
org.apache.kylin.storage.hbase.cube.v2.CubeHBaseEndpointRPC$2.call(CubeHBaseEndpointRPC.java:361)
at
org.apache.kylin.storage.hbase.cube.v2.CubeHBaseEndpointRPC$2.call(CubeHBaseEndpointRPC.java:357)
at org.apache.hadoop.hbase.client.HTable$15.call(HTable.java:1797)
... 4 more
Caused by:
org.apache.hadoop.hbase.ipc.RemoteWithExtrasException(org.apache.hadoop.hbase.DoNotRetryIOException):
org.apache.hadoop.hbase.DoNotRetryIOException: java.lang.NoSuchMethodError:
org.apache.hadoop.hbase.coprocessor.RegionCoprocessorEnvironment.getRegion()Lorg/apache/hadoop/hbase/regionserver/HRegion;
at org.apache.hadoop.hbase.ipc.RpcServer.call(RpcServer.java:2212)
at org.apache.hadoop.hbase.ipc.CallRunner.run(CallRunner.java:109)
at
org.apache.hadoop.hbase.ipc.RpcExecutor.consumerLoop(RpcExecutor.java:133)
at org.apache.hadoop.hbase.ipc.RpcExecutor$1.run(RpcExecutor.java:108)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.lang.NoSuchMethodError:
org.apache.hadoop.hbase.coprocessor.RegionCoprocessorEnvironment.getRegion()Lorg/apache/hadoop/hbase/regionserver/HRegion;
at
org.apache.kylin.storage.hbase.cube.v2.coprocessor.endpoint.CubeVisitService.visitCube(CubeVisitService.java:158)
at
org.apache.kylin.storage.hbase.cube.v2.coprocessor.endpoint.generated.CubeVisitProtos$CubeVisitService.callMethod(CubeVisitProtos.java:3800)
at
org.apache.hadoop.hbase.regionserver.HRegion.execService(HRegion.java:7789)
at
org.apache.hadoop.hbase.regionserver.RSRpcServices.execServiceOnRegion(RSRpcServices.java:1988)
at
org.apache.hadoop.hbase.regionserver.RSRpcServices.execService(RSRpcServices.java:1970)
at
org.apache.hadoop.hbase.protobuf.generated.ClientProtos$ClientService$2.callBlockingMethod(ClientProtos.java:33652)
at org.apache.hadoop.hbase.ipc.RpcServer.call(RpcServer.java:2170)
... 4 more
at org.apache.hadoop.hbase.ipc.RpcClientImpl.call(RpcClientImpl.java:1268)
at
org.apache.hadoop.hbase.ipc.AbstractRpcClient.callBlockingMethod(AbstractRpcClient.java:226)
at
org.apache.hadoop.hbase.ipc.AbstractRpcClient$BlockingRpcChannelImplementation.callBlockingMethod(AbstractRpcClient.java:331)
at
org.apache.hadoop.hbase.protobuf.generated.ClientProtos$ClientService$BlockingStub.execService(ClientProtos.java:34118)
at
org.apache.hadoop.hbase.protobuf.ProtobufUtil.execService(ProtobufUtil.java:1620)
... 13 more
2016-05-03 15:04:05,474 INFO [pool-5-thread-1]
threadpool.DefaultScheduler:106 : Job Fetcher: 0 running, 0 actual running,
0 ready, 6 others
2016-05-03 15:04:21,229 ERROR [http-bio-7070-exec-5]
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$$f73318de.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:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
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:201)
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:1145)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
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 15:04:21,233 INFO [http-bio-7070-exec-5]
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 LIMIT 50000
User: ADMIN
Success: false
Duration: 0.0
Project: Tutorial
Realization Names: [Tutorial_Cube]
Cuboid Ids: [8]
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 15:04:21,237 ERROR [http-bio-7070-exec-5]
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:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
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:201)
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:1145)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.lang.Thread.run(Thread.java:745)
===============================================================================================================================================
On Tue, May 3, 2016 at 9:56 PM, hongbin ma <ma...@apache.org> wrote:
> Hi Amit
>
> seems no attachment is found
>
> On Tue, May 3, 2016 at 11:09 PM, Amit Jain <am...@gmail.com> wrote:
>
> > Hi Li,
> > Log file is attached. Please let me know your findings.
> > Thanks for your help.
> > Amit
> >
> > On Tue, May 3, 2016 at 5:00 AM, Li Yang <li...@apache.org> wrote:
> >
> >> Could you share kylin.log? There must be something else behind the
> timeout
> >> error.
> >>
> >> On Fri, Apr 29, 2016 at 11:53 AM, Amit Jain <am...@gmail.com> wrote:
> >>
> >> > I'm getting following error when running the query as described on
> >> > http://kylin.apache.org/docs15/tutorial/kylin_sample.html
> >> > I'm get to the step where the cube build succeeds but when I run the
> >> query
> >> > on Insight, I get the error.
> >> >
> >> > 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!
> >> >
> >> > Please note that I'm running this on a 5 Node Hadoop Cluster with
> >> CDH5.7 on
> >> > AWS. I tried installing Kylin on datanode first and got this error.
> >> Later I
> >> > installed Kylin on namenode and still getting the same error message.
> >> > I can make Kylin work on CDH VM with single node but not on a Hadoop
> >> > multinode cluster.
> >> > Please let me know how to fix this.
> >> > Thanks
> >> > Amit
> >> >
> >>
> >
> >
>
>
> --
> Regards,
>
> *Bin Mahone | 马洪宾*
> Apache Kylin: http://kylin.io
> Github: https://github.com/binmahone
>
Re: Timeout visiting cube!
Posted by hongbin ma <ma...@apache.org>.
Hi Amit
seems no attachment is found
On Tue, May 3, 2016 at 11:09 PM, Amit Jain <am...@gmail.com> wrote:
> Hi Li,
> Log file is attached. Please let me know your findings.
> Thanks for your help.
> Amit
>
> On Tue, May 3, 2016 at 5:00 AM, Li Yang <li...@apache.org> wrote:
>
>> Could you share kylin.log? There must be something else behind the timeout
>> error.
>>
>> On Fri, Apr 29, 2016 at 11:53 AM, Amit Jain <am...@gmail.com> wrote:
>>
>> > I'm getting following error when running the query as described on
>> > http://kylin.apache.org/docs15/tutorial/kylin_sample.html
>> > I'm get to the step where the cube build succeeds but when I run the
>> query
>> > on Insight, I get the error.
>> >
>> > 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!
>> >
>> > Please note that I'm running this on a 5 Node Hadoop Cluster with
>> CDH5.7 on
>> > AWS. I tried installing Kylin on datanode first and got this error.
>> Later I
>> > installed Kylin on namenode and still getting the same error message.
>> > I can make Kylin work on CDH VM with single node but not on a Hadoop
>> > multinode cluster.
>> > Please let me know how to fix this.
>> > Thanks
>> > Amit
>> >
>>
>
>
--
Regards,
*Bin Mahone | 马洪宾*
Apache Kylin: http://kylin.io
Github: https://github.com/binmahone
Re: Timeout visiting cube!
Posted by Amit Jain <am...@gmail.com>.
Hi Li,
Log file is attached. Please let me know your findings.
Thanks for your help.
Amit
On Tue, May 3, 2016 at 5:00 AM, Li Yang <li...@apache.org> wrote:
> Could you share kylin.log? There must be something else behind the timeout
> error.
>
> On Fri, Apr 29, 2016 at 11:53 AM, Amit Jain <am...@gmail.com> wrote:
>
> > I'm getting following error when running the query as described on
> > http://kylin.apache.org/docs15/tutorial/kylin_sample.html
> > I'm get to the step where the cube build succeeds but when I run the
> query
> > on Insight, I get the error.
> >
> > 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!
> >
> > Please note that I'm running this on a 5 Node Hadoop Cluster with CDH5.7
> on
> > AWS. I tried installing Kylin on datanode first and got this error.
> Later I
> > installed Kylin on namenode and still getting the same error message.
> > I can make Kylin work on CDH VM with single node but not on a Hadoop
> > multinode cluster.
> > Please let me know how to fix this.
> > Thanks
> > Amit
> >
>
Re: Timeout visiting cube!
Posted by Li Yang <li...@apache.org>.
Could you share kylin.log? There must be something else behind the timeout
error.
On Fri, Apr 29, 2016 at 11:53 AM, Amit Jain <am...@gmail.com> wrote:
> I'm getting following error when running the query as described on
> http://kylin.apache.org/docs15/tutorial/kylin_sample.html
> I'm get to the step where the cube build succeeds but when I run the query
> on Insight, I get the error.
>
> 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!
>
> Please note that I'm running this on a 5 Node Hadoop Cluster with CDH5.7 on
> AWS. I tried installing Kylin on datanode first and got this error. Later I
> installed Kylin on namenode and still getting the same error message.
> I can make Kylin work on CDH VM with single node but not on a Hadoop
> multinode cluster.
> Please let me know how to fix this.
> Thanks
> Amit
>