[
https://issues.apache.org/jira/browse/LENS-1026?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15259703#comment-15259703
]
Puneet Gupta commented on LENS-1026:
------------------------------------
Will update the test case to make sure only RUNNING queries are tested.
> Seeing intermittent test failure in
> org.apache.lens.server.query.TestQueryService.testSessionClose
> --------------------------------------------------------------------------------------------------
>
> Key: LENS-1026
> URL: https://issues.apache.org/jira/browse/LENS-1026
> Project: Apache Lens
> Issue Type: Bug
> Components: server
> Reporter: Puneet Gupta
> Assignee: Puneet Gupta
> Priority: Minor
>
> *Error Message*
> expected [SUCCESSFUL] but found [FAILED]
> *Stacktrace*
> {noformat}java.lang.AssertionError: expected [SUCCESSFUL] but found [FAILED]
> at org.testng.Assert.fail(Assert.java:94)
> at org.testng.Assert.failNotEquals(Assert.java:494)
> at org.testng.Assert.assertEquals(Assert.java:123)
> at org.testng.Assert.assertEquals(Assert.java:165)
> at
> org.apache.lens.server.common.RestAPITestUtil.waitForQueryToFinish(RestAPITestUtil.java:231)
> at
> org.apache.lens.server.query.TestQueryService.testSessionClose(TestQueryService.java:1791)
> {noformat}
> *Standard Output*
> {noformat}
> @@@@ Got Event: Type= org.apache.lens.server.api.session.SessionOpened obj =
> SessionOpened(super=SessionEvent(sessionHandle=<?xml version="1.0"
> encoding="UTF-8"
> standalone="yes"?><lensSessionHandle><publicId>5a8c9143-cc32-4051-bade-c9215b81bfa7</publicId><secretId>f4cdfc0a-645c-4ea8-9c4f-f2482948226f</secretId></lensSessionHandle>,
> id=f69a49be-eb1c-4562-8c8a-378daec5ea40), user=foo)
> @@@@ Got Event: Type= org.apache.lens.server.api.query.QueryAccepted obj =
> QueryEvent: QueryAccepted:{id: be6fe2ca-64df-4a38-b234-f7ed0d4a82c3,
> query:null, change:[null -> select ID, IDSTR, count(*) from TEST_TABLE group
> by ID, IDSTR]}
> 27 Apr 2016 06:18:26 [931ade68-b394-422d-9265-297e77b935df] [estimate-4]
> ERROR org.apache.lens.server.api.query.AbstractQueryContext - Setting driver
> cost failed for driver mock/fail1:1 Cause: Driver :mock/fail1 Cause
> :Simulated Estimate Failure
> org.apache.lens.server.api.error.LensException: Simulated Estimate Failure
> at
> org.apache.lens.server.common.FailingQueryDriver.estimate(FailingQueryDriver.java:37)
> ~[test-classes/:na]
> at
> org.apache.lens.server.api.query.AbstractQueryContext$DriverEstimateRunnable.run(AbstractQueryContext.java:258)
> ~[lens-server-api-2.6.0-beta-SNAPSHOT.jar:2.6.0-beta-SNAPSHOT]
> at
> org.apache.lens.server.query.QueryExecutionServiceImpl$RewriteEstimateRunnable.run(QueryExecutionServiceImpl.java:1496)
> [classes/:na]
> at
> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
> [na:1.8.0_77]
> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
> [na:1.8.0_77]
> at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
> [na:1.8.0_77]
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
> [na:1.8.0_77]
> at java.lang.Thread.run(Thread.java:745) [na:1.8.0_77]
> 27 Apr 2016 06:18:26 [931ade68-b394-422d-9265-297e77b935df] [estimate-4]
> ERROR org.apache.lens.server.query.QueryExecutionServiceImpl - Estimate
> failed for driver mock/fail1:1 cause: Driver :mock/fail1 Cause :Simulated
> Estimate Failure
> 27 Apr 2016 06:18:26 [931ade68-b394-422d-9265-297e77b935df] [estimate-3]
> ERROR org.apache.lens.server.api.query.AbstractQueryContext - Setting driver
> cost failed for driver jdbc/jdbc1 Cause: Driver :jdbc/jdbc1 Cause :user lacks
> privilege or object not found: TEST_TABLE
> org.apache.lens.server.api.error.LensException: SEMANTIC_ERROR[user lacks
> privilege or object not found: TEST_TABLE]
> at
> org.apache.lens.driver.jdbc.JDBCDriver.handleJDBCSQLException(JDBCDriver.java:824)
> ~[lens-driver-jdbc-2.6.0-beta-SNAPSHOT.jar:2.6.0-beta-SNAPSHOT]
> at
> org.apache.lens.driver.jdbc.JDBCDriver.prepareInternal(JDBCDriver.java:800)
> ~[lens-driver-jdbc-2.6.0-beta-SNAPSHOT.jar:2.6.0-beta-SNAPSHOT]
> at org.apache.lens.driver.jdbc.JDBCDriver.validate(JDBCDriver.java:678)
> ~[lens-driver-jdbc-2.6.0-beta-SNAPSHOT.jar:2.6.0-beta-SNAPSHOT]
> at org.apache.lens.driver.jdbc.JDBCDriver.estimate(JDBCDriver.java:607)
> ~[lens-driver-jdbc-2.6.0-beta-SNAPSHOT.jar:2.6.0-beta-SNAPSHOT]
> at
> org.apache.lens.server.api.query.AbstractQueryContext$DriverEstimateRunnable.run(AbstractQueryContext.java:258)
> ~[lens-server-api-2.6.0-beta-SNAPSHOT.jar:2.6.0-beta-SNAPSHOT]
> at
> org.apache.lens.server.query.QueryExecutionServiceImpl$RewriteEstimateRunnable.run(QueryExecutionServiceImpl.java:1496)
> [classes/:na]
> at
> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
> [na:1.8.0_77]
> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
> [na:1.8.0_77]
> at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
> [na:1.8.0_77]
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
> [na:1.8.0_77]
> at java.lang.Thread.run(Thread.java:745) [na:1.8.0_77]
> Caused by: java.sql.SQLException: A problem occurred while trying to acquire
> a cached PreparedStatement in a background thread.
> at
> com.mchange.v2.c3p0.stmt.GooGooStatementCache.acquireStatement(GooGooStatementCache.java:571)
> ~[c3p0-0.9.5.jar:0.9.5]
> at
> com.mchange.v2.c3p0.stmt.GooGooStatementCache.checkoutStatement(GooGooStatementCache.java:204)
> ~[c3p0-0.9.5.jar:0.9.5]
> at
> com.mchange.v2.c3p0.impl.NewPooledConnection.checkoutStatement(NewPooledConnection.java:321)
> ~[c3p0-0.9.5.jar:0.9.5]
> at
> com.mchange.v2.c3p0.impl.NewProxyConnection.prepareStatement(NewProxyConnection.java:455)
> ~[c3p0-0.9.5.jar:0.9.5]
> at
> org.apache.lens.driver.jdbc.JDBCDriver.prepareInternal(JDBCDriver.java:795)
> ~[lens-driver-jdbc-2.6.0-beta-SNAPSHOT.jar:2.6.0-beta-SNAPSHOT]
> ... 9 common frames omitted
> Caused by: java.sql.SQLSyntaxErrorException: user lacks privilege or object
> not found: TEST_TABLE
> at org.hsqldb.jdbc.Util.sqlException(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.jdbc.Util.sqlException(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.jdbc.JDBCPreparedStatement.<init>(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.jdbc.JDBCConnection.prepareStatement(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at sun.reflect.GeneratedMethodAccessor236.invoke(Unknown Source)
> ~[na:na]
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> ~[na:1.8.0_77]
> at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_77]
> at
> com.mchange.v2.c3p0.stmt.GooGooStatementCache$1StmtAcquireTask.run(GooGooStatementCache.java:546)
> ~[c3p0-0.9.5.jar:0.9.5]
> at
> com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:696)
> ~[mchange-commons-java-0.2.9.jar:0.2.9]
> Caused by: org.hsqldb.HsqlException: user lacks privilege or object not
> found: TEST_TABLE
> at org.hsqldb.error.Error.error(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.error.Error.error(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.SchemaManager.getTable(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.ParserDQL.readTableName(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.ParserDQL.readTableOrSubquery(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.ParserDQL.XreadTableReference(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.ParserDQL.XreadFromClause(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.ParserDQL.XreadTableExpression(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.ParserDQL.XreadQuerySpecification(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.ParserDQL.XreadSimpleTable(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.ParserDQL.XreadQueryPrimary(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.ParserDQL.XreadQueryTerm(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.ParserDQL.XreadQueryExpressionBody(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.ParserDQL.XreadQueryExpression(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.ParserDQL.compileCursorSpecification(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.ParserCommand.compilePart(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.ParserCommand.compileStatement(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.Session.compileStatement(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.StatementManager.compile(Unknown Source)
> ~[hsqldb-2.2.9.jar:2.2.9]
> at org.hsqldb.Session.execute(Unknown Source) ~[hsqldb-2.2.9.jar:2.2.9]
> ... 7 common frames omitted
> 27 Apr 2016 06:18:26 [931ade68-b394-422d-9265-297e77b935df] [estimate-3]
> ERROR org.apache.lens.server.query.QueryExecutionServiceImpl - Estimate
> failed for driver jdbc/jdbc1 cause: Driver :jdbc/jdbc1 Cause :user lacks
> privilege or object not found: TEST_TABLE
> @@@@ Got Event: Type= org.apache.lens.server.api.query.QueryQueued obj =
> QueryEvent: QueryQueued:{id: 050697fe-4da8-438c-95de-2abb070460c5,
> query:931ade68-b394-422d-9265-297e77b935df, change:[NEW -> QUEUED]}
> 27 Apr 2016 06:18:26 [931ade68-b394-422d-9265-297e77b935df] [QuerySubmitter]
> ERROR org.apache.lens.server.query.QueryExecutionServiceImpl - Error
> launching query: 931ade68-b394-422d-9265-297e77b935df
> org.apache.lens.server.api.error.LensException: DRIVER_ERROR[Invalid
> SessionHandle: SessionHandle [9860dfbd-a403-4b16-bab6-99a9160ec343]]
> at
> org.apache.lens.driver.hive.HiveDriver.handleHiveSQLException(HiveDriver.java:581)
> ~[lens-driver-hive-2.6.0-beta-SNAPSHOT.jar:2.6.0-beta-SNAPSHOT]
> at
> org.apache.lens.driver.hive.HiveDriver.executeAsync(HiveDriver.java:573)
> ~[lens-driver-hive-2.6.0-beta-SNAPSHOT.jar:2.6.0-beta-SNAPSHOT]
> at
> org.apache.lens.server.query.QueryExecutionServiceImpl$QuerySubmitter.launchQuery(QueryExecutionServiceImpl.java:740)
> ~[classes/:na]
> at
> org.apache.lens.server.query.QueryExecutionServiceImpl$QuerySubmitter.run(QueryExecutionServiceImpl.java:697)
> ~[classes/:na]
> at java.lang.Thread.run(Thread.java:745) [na:1.8.0_77]
> Caused by: org.apache.hive.service.cli.HiveSQLException: Invalid
> SessionHandle: SessionHandle [9860dfbd-a403-4b16-bab6-99a9160ec343]
> at
> org.apache.hive.service.cli.thrift.ThriftCLIServiceClient.checkStatus(ThriftCLIServiceClient.java:52)
> ~[hive-service-0.13.3-inm.jar:0.13.3-inm]
> at
> org.apache.hive.service.cli.thrift.ThriftCLIServiceClient.executeStatementInternal(ThriftCLIServiceClient.java:151)
> ~[hive-service-0.13.3-inm.jar:0.13.3-inm]
> at
> org.apache.hive.service.cli.thrift.ThriftCLIServiceClient.executeStatementAsync(ThriftCLIServiceClient.java:139)
> ~[hive-service-0.13.3-inm.jar:0.13.3-inm]
> at sun.reflect.GeneratedMethodAccessor195.invoke(Unknown Source)
> ~[na:na]
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> ~[na:1.8.0_77]
> at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_77]
> at
> org.apache.hive.service.cli.thrift.RetryingThriftCLIServiceClient.invokeInternal(RetryingThriftCLIServiceClient.java:301)
> ~[hive-service-0.13.3-inm.jar:0.13.3-inm]
> at
> org.apache.hive.service.cli.thrift.RetryingThriftCLIServiceClient.invoke(RetryingThriftCLIServiceClient.java:329)
> ~[hive-service-0.13.3-inm.jar:0.13.3-inm]
> at com.sun.proxy.$Proxy87.executeStatementAsync(Unknown Source) ~[na:na]
> at
> org.apache.hive.service.cli.thrift.RetryingThriftCLIServiceClient$CLIServiceClientWrapper.executeStatementAsync(RetryingThriftCLIServiceClient.java:118)
> ~[hive-service-0.13.3-inm.jar:0.13.3-inm]
> at
> org.apache.lens.driver.hive.HiveDriver.executeAsync(HiveDriver.java:563)
> ~[lens-driver-hive-2.6.0-beta-SNAPSHOT.jar:2.6.0-beta-SNAPSHOT]
> ... 3 common frames omitted
> @@@@ Got Event: Type= org.apache.lens.server.api.session.SessionClosed obj =
> SessionClosed(super=SessionEvent(sessionHandle=<?xml version="1.0"
> encoding="UTF-8"
> standalone="yes"?><lensSessionHandle><publicId>5a8c9143-cc32-4051-bade-c9215b81bfa7</publicId><secretId>f4cdfc0a-645c-4ea8-9c4f-f2482948226f</secretId></lensSessionHandle>,
> id=4d45239a-e3de-4a30-bd9f-caa8f87ddd86))
> @@@@ Got Event: Type= org.apache.lens.server.api.session.SessionOpened obj =
> SessionOpened(super=SessionEvent(sessionHandle=<?xml version="1.0"
> encoding="UTF-8"
> standalone="yes"?><lensSessionHandle><publicId>c9d989aa-ab72-4776-8c50-df2149e7e98f</publicId><secretId>bd92468a-8b25-40ea-a995-7f1f29d1c71c</secretId></lensSessionHandle>,
> id=f9e5396f-95cf-427f-9693-4f24898ca738), user=foo)
> @@@@ Got Event: Type= org.apache.lens.server.api.query.QueryFailed obj =
> QueryEvent: QueryFailed:{id: 037c6970-0884-437f-a715-3d9f5127259e,
> query:931ade68-b394-422d-9265-297e77b935df, change:[QUEUED -> FAILED]}
> cause:Launching query failed
> Reason:
> Invalid SessionHandle: SessionHandle [9860dfbd-a403-4b16-bab6-99a9160ec343]
> @@@@ Got Event: Type=
> org.apache.lens.server.stats.event.query.QueryExecutionStatistics obj =
> org.apache.lens.server.stats.event.query.QueryExecutionStatistics@b8c5863
> {noformat}
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
