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 <[email protected]> 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 <[email protected]> wrote:
>
> > Hi Amit
> >
> > seems no attachment is found
> >
> > On Tue, May 3, 2016 at 11:09 PM, Amit Jain <[email protected]> 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 <[email protected]> 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 <[email protected]> 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

Reply via email to