Puneet Gupta created LENS-1026:
----------------------------------
Summary: 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
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)
