Return-Path: X-Original-To: apmail-asterixdb-notifications-archive@minotaur.apache.org Delivered-To: apmail-asterixdb-notifications-archive@minotaur.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 8AE1D18919 for ; Tue, 17 Nov 2015 17:07:23 +0000 (UTC) Received: (qmail 19575 invoked by uid 500); 17 Nov 2015 17:07:23 -0000 Delivered-To: apmail-asterixdb-notifications-archive@asterixdb.apache.org Received: (qmail 19546 invoked by uid 500); 17 Nov 2015 17:07:23 -0000 Mailing-List: contact notifications-help@asterixdb.incubator.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@asterixdb.incubator.apache.org Delivered-To: mailing list notifications@asterixdb.incubator.apache.org Received: (qmail 19537 invoked by uid 99); 17 Nov 2015 17:07:23 -0000 Received: from Unknown (HELO spamd3-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 17 Nov 2015 17:07:23 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd3-us-west.apache.org (ASF Mail Server at spamd3-us-west.apache.org) with ESMTP id B30BC180A18 for ; Tue, 17 Nov 2015 17:07:22 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd3-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: 0.97 X-Spam-Level: X-Spam-Status: No, score=0.97 tagged_above=-999 required=6.31 tests=[KAM_LAZY_DOMAIN_SECURITY=1, RCVD_IN_MSPIKE_H3=-0.01, RCVD_IN_MSPIKE_WL=-0.01, T_RP_MATCHES_RCVD=-0.01] autolearn=disabled Received: from mx1-eu-west.apache.org ([10.40.0.8]) by localhost (spamd3-us-west.apache.org [10.40.0.10]) (amavisd-new, port 10024) with ESMTP id IT6-BNdrm_6c for ; Tue, 17 Nov 2015 17:07:14 +0000 (UTC) Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by mx1-eu-west.apache.org (ASF Mail Server at mx1-eu-west.apache.org) with SMTP id 0AF382304C for ; Tue, 17 Nov 2015 17:07:11 +0000 (UTC) Received: (qmail 17182 invoked by uid 99); 17 Nov 2015 17:07:11 -0000 Received: from arcas.apache.org (HELO arcas) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 17 Nov 2015 17:07:11 +0000 Received: from arcas.apache.org (localhost [127.0.0.1]) by arcas (Postfix) with ESMTP id 0AB992C14FB for ; Tue, 17 Nov 2015 17:07:11 +0000 (UTC) Date: Tue, 17 Nov 2015 17:07:11 +0000 (UTC) From: "Yingyi Bu (JIRA)" To: notifications@asterixdb.incubator.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Commented] (ASTERIXDB-1159) Sporadic test failures in nested-open-index test MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 7bit X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 [ https://issues.apache.org/jira/browse/ASTERIXDB-1159?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15009032#comment-15009032 ] Yingyi Bu commented on ASTERIXDB-1159: -------------------------------------- One additional issue is that if we run into this exception, a consequent "drop dataverse ..." will hang with the following trace: Daemon Thread [org.apache.hyracks.api.rewriter.runtime.SuperActivity:TAID:TID:ANID:ODID:0:0:3:0:0] (Suspended) owns: DatasetLifecycleManager (id=1/setInfo (id=10367) Object.wait(long) line: not available [native method] DatasetLifecycleManager$DatasetInfo(Object).wait() line: 502 [local variables unavailable] DatasetLifecycleManager.flushAndWaitForIO(DatasetLifecycleManager$DatasetInfo, DatasetLifecycleManager$IndexInfo) line: 244 DatasetLifecycleManager.unregister(String) line: 167 LSMBTreeDataflowHelper(IndexDataflowHelper).destroy() line: 132 IndexDropOperatorNodePushable.initialize() line: 53 SuperActivityOperatorNodePushable.initialize() line: 85 Task.run() line: 255 ThreadPoolExecutor.runWorker(ThreadPoolExecutor$Worker) line: 1142 ThreadPoolExecutor$Worker.run() line: 617 Thread.run() line: 745 It seems some locks were not released. > Sporadic test failures in nested-open-index test > ------------------------------------------------ > > Key: ASTERIXDB-1159 > URL: https://issues.apache.org/jira/browse/ASTERIXDB-1159 > Project: Apache AsterixDB > Issue Type: Bug > Components: Data Formats > Reporter: Yingyi Bu > Assignee: Ildar Absalyamov > Priority: Critical > Labels: sporadic-error > > The test case is > src/test/resources/runtimets/queries/nested-open-index/index-join/ngram-edit-distance/ngram-edit-distance.3.ddl.aql, which is to create an enforced index. > org.apache.hyracks.api.exceptions.HyracksDataException: org.apache.hyracks.algebricks.common.exceptions.AlgebricksException: java.lang.IllegalStateException: java.lang.ArrayIndexOutOfBoundsException: 16777216 > at org.apache.hyracks.algebricks.runtime.operators.std.AssignRuntimeFactory$1.produceTuple(AssignRuntimeFactory.java:150) > at org.apache.hyracks.algebricks.runtime.operators.std.AssignRuntimeFactory$1.nextFrame(AssignRuntimeFactory.java:125) > at org.apache.hyracks.algebricks.runtime.operators.meta.AlgebricksMetaOperatorDescriptor$2.nextFrame(AlgebricksMetaOperatorDescriptor.java:145) > at org.apache.hyracks.dataflow.common.comm.io.AbstractFrameAppender.flush(AbstractFrameAppender.java:83) > at org.apache.hyracks.storage.am.common.dataflow.IndexSearchOperatorNodePushable.close(IndexSearchOperatorNodePushable.java:196) > at org.apache.hyracks.dataflow.std.misc.ConstantTupleSourceOperatorNodePushable.initialize(ConstantTupleSourceOperatorNodePushable.java:54) > at org.apache.hyracks.api.rewriter.runtime.SuperActivityOperatorNodePushable.initialize(SuperActivityOperatorNodePushable.java:85) > at org.apache.hyracks.control.nc.Task.run(Task.java:255) > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) > at java.lang.Thread.run(Thread.java:745) > Caused by: org.apache.hyracks.algebricks.common.exceptions.AlgebricksException: java.lang.IllegalStateException: java.lang.ArrayIndexOutOfBoundsException: 16777216 > at org.apache.asterix.runtime.evaluators.functions.CastRecordDescriptor$2$1.evaluate(CastRecordDescriptor.java:95) > at org.apache.hyracks.algebricks.core.algebra.expressions.LogicalExpressionJobGenToExpressionRuntimeProviderAdapter$ScalarEvaluatorFactoryAdapter$1.evaluate(LogicalExpressionJobGenToExpressionRuntimeProviderAdapter.java:110) > at org.apache.hyracks.algebricks.runtime.operators.std.AssignRuntimeFactory$1.produceTuple(AssignRuntimeFactory.java:148) > ... 10 more > Caused by: java.lang.IllegalStateException: java.lang.ArrayIndexOutOfBoundsException: 16777216 > at org.apache.asterix.om.pointables.ARecordVisitablePointable.set(ARecordVisitablePointable.java:266) > at org.apache.asterix.om.pointables.AbstractVisitablePointable.set(AbstractVisitablePointable.java:59) > at org.apache.asterix.runtime.evaluators.functions.CastRecordDescriptor$2$1.evaluate(CastRecordDescriptor.java:90) > ... 12 more > Caused by: java.lang.ArrayIndexOutOfBoundsException: 16777216 > at org.apache.asterix.dataflow.data.nontagged.serde.AInt32SerializerDeserializer.getInt(AInt32SerializerDeserializer.java:50) > at org.apache.asterix.dataflow.data.nontagged.serde.ARecordSerializerDeserializer.getRecordLength(ARecordSerializerDeserializer.java:236) > at org.apache.asterix.om.util.NonTaggedFormatUtil.getFieldValueLength(NonTaggedFormatUtil.java:165) > at org.apache.asterix.om.pointables.ARecordVisitablePointable.set(ARecordVisitablePointable.java:219) > ... 14 more > org.apache.hyracks.api.exceptions.HyracksException: Job failed on account of: > org.apache.hyracks.api.exceptions.HyracksDataException: org.apache.hyracks.algebricks.common.exceptions.AlgebricksException: java.lang.IllegalStateException: java.lang.ArrayIndexOutOfBoundsException: 16777216 > at org.apache.hyracks.control.cc.job.JobRun.waitForCompletion(JobRun.java:211) > at org.apache.hyracks.control.cc.work.WaitForJobCompletionWork$1.run(WaitForJobCompletionWork.java:48) > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) > at java.lang.Thread.run(Thread.java:745) > Caused by: org.apache.hyracks.api.exceptions.HyracksDataException: org.apache.hyracks.api.exceptions.HyracksDataException: org.apache.hyracks.algebricks.common.exceptions.AlgebricksException: java.lang.IllegalStateException: java.lang.ArrayIndexOutOfBoundsException: 16777216 > at org.apache.hyracks.control.common.utils.ExceptionUtils.setNodeIds(ExceptionUtils.java:45) > at org.apache.hyracks.control.nc.Task.run(Task.java:312) > ... 3 more > Caused by: org.apache.hyracks.api.exceptions.HyracksDataException: org.apache.hyracks.algebricks.common.exceptions.AlgebricksException: java.lang.IllegalStateException: java.lang.ArrayIndexOutOfBoundsException: 16777216 > at org.apache.hyracks.algebricks.runtime.operators.std.AssignRuntimeFactory$1.produceTuple(AssignRuntimeFactory.java:150) > at org.apache.hyracks.algebricks.runtime.operators.std.AssignRuntimeFactory$1.nextFrame(AssignRuntimeFactory.java:125) > at org.apache.hyracks.algebricks.runtime.operators.meta.AlgebricksMetaOperatorDescriptor$2.nextFrame(AlgebricksMetaOperatorDescriptor.java:145) > at org.apache.hyracks.dataflow.common.comm.io.AbstractFrameAppender.flush(AbstractFrameAppender.java:83) > at org.apache.hyracks.storage.am.common.dataflow.IndexSearchOperatorNodePushable.close(IndexSearchOperatorNodePushable.java:196) > at org.apache.hyracks.dataflow.std.misc.ConstantTupleSourceOperatorNodePushable.initialize(ConstantTupleSourceOperatorNodePushable.java:54) > at org.apache.hyracks.api.rewriter.runtime.SuperActivityOperatorNodePushable.initialize(SuperActivityOperatorNodePushable.java:85) > at org.apache.hyracks.control.nc.Task.run(Task.java:255) > ... 3 more > Caused by: org.apache.hyracks.algebricks.common.exceptions.AlgebricksException: java.lang.IllegalStateException: java.lang.ArrayIndexOutOfBoundsException: 16777216 > at org.apache.asterix.runtime.evaluators.functions.CastRecordDescriptor$2$1.evaluate(CastRecordDescriptor.java:95) > at org.apache.hyracks.algebricks.core.algebra.expressions.LogicalExpressionJobGenToExpressionRuntimeProviderAdapter$ScalarEvaluatorFactoryAdapter$1.evaluate(LogicalExpressionJobGenToExpressionRuntimeProviderAdapter.java:110) > at org.apache.hyracks.algebricks.runtime.operators.std.AssignRuntimeFactory$1.produceTuple(AssignRuntimeFactory.java:148) > ... 10 more > Caused by: java.lang.IllegalStateException: java.lang.ArrayIndexOutOfBoundsException: 16777216 > at org.apache.asterix.om.pointables.ARecordVisitablePointable.set(ARecordVisitablePointable.java:266) > at org.apache.asterix.om.pointables.AbstractVisitablePointable.set(AbstractVisitablePointable.java:59) > at org.apache.asterix.runtime.evaluators.functions.CastRecordDescriptor$2$1.evaluate(CastRecordDescriptor.java:90) > ... 12 more > Caused by: java.lang.ArrayIndexOutOfBoundsException: 16777216 > at org.apache.asterix.dataflow.data.nontagged.serde.AInt32SerializerDeserializer.getInt(AInt32SerializerDeserializer.java:50) > at org.apache.asterix.dataflow.data.nontagged.serde.ARecordSerializerDeserializer.getRecordLength(ARecordSerializerDeserializer.java:236) > at org.apache.asterix.om.util.NonTaggedFormatUtil.getFieldValueLength(NonTaggedFormatUtil.java:165) > at org.apache.asterix.om.pointables.ARecordVisitablePointable.set(ARecordVisitablePointable.java:219) > ... 14 more > testFile src/test/resources/runtimets/queries/nested-open-index/index-join/ngram-edit-distance/ngram-edit-distance.3.ddl.aql raised an exception: > java.lang.Exception: HTTP operation failed: 99 > STATUS LINE: HTTP/1.1 500 Server Error > SUMMARY: Job failed on account of: > org.apache.hyracks.api.exceptions.HyracksDataException: org.apache.hyracks.algebricks.common.exceptions.AlgebricksException: java.lang.IllegalStateException: java.lang.ArrayIndexOutOfBoundsException: 16777216 > caused by: org.apache.hyracks.control.cc.job.JobRun.waitForCompletion(JobRun.java:211) > caused by: org.apache.hyracks.control.common.utils.ExceptionUtils.setNodeIds(ExceptionUtils.java:45) > caused by: org.apache.hyracks.algebricks.runtime.operators.std.AssignRuntimeFactory$1.produceTuple(AssignRuntimeFactory.java:150) > caused by: org.apache.asterix.runtime.evaluators.functions.CastRecordDescriptor$2$1.evaluate(CastRecordDescriptor.java:95) > caused by: org.apache.asterix.om.pointables.ARecordVisitablePointable.set(ARecordVisitablePointable.java:266) > caused by: org.apache.asterix.dataflow.data.nontagged.serde.AInt32SerializerDeserializer.getInt(AInt32SerializerDeserializer.java:50) > STACKTRACE: org.apache.hyracks.api.exceptions.HyracksException: Job failed on account of: > org.apache.hyracks.api.exceptions.HyracksDataException: org.apache.hyracks.algebricks.common.exceptions.AlgebricksException: java.lang.IllegalStateException: java.lang.ArrayIndexOutOfBoundsException: 16777216 > at org.apache.hyracks.control.cc.job.JobRun.waitForCompletion(JobRun.java:211) > at org.apache.hyracks.control.cc.work.WaitForJobCompletionWork$1.run(WaitForJobCompletionWork.java:48) > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) > at java.lang.Thread.run(Thread.java:745) > Caused by: org.apache.hyracks.api.exceptions.HyracksDataException: org.apache.hyracks.api.exceptions.HyracksDataException: org.apache.hyracks.algebricks.common.exceptions.AlgebricksException: java.lang.IllegalStateException: java.lang.ArrayIndexOutOfBoundsException: 16777216 > at org.apache.hyracks.control.common.utils.ExceptionUtils.setNodeIds(ExceptionUtils.java:45) > at org.apache.hyracks.control.nc.Task.run(Task.java:312) > ... 3 more > Caused by: org.apache.hyracks.api.exceptions.HyracksDataException: org.apache.hyracks.algebricks.common.exceptions.AlgebricksException: java.lang.IllegalStateException: java.lang.ArrayIndexOutOfBoundsException: 16777216 > at org.apache.hyracks.algebricks.runtime.operators.std.AssignRuntimeFactory$1.produceTuple(AssignRuntimeFactory.java:150) > at org.apache.hyracks.algebricks.runtime.operators.std.AssignRuntimeFactory$1.nextFrame(AssignRuntimeFactory.java:125) > at org.apache.hyracks.algebricks.runtime.operators.meta.AlgebricksMetaOperatorDescriptor$2.nextFrame(AlgebricksMetaOperatorDescriptor.java:145) > at org.apache.hyracks.dataflow.common.comm.io.AbstractFrameAppender.flush(AbstractFrameAppender.java:83) > at org.apache.hyracks.storage.am.common.dataflow.IndexSearchOperatorNodePushable.close(IndexSearchOperatorNodePushable.java:196) > at org.apache.hyracks.dataflow.std.misc.ConstantTupleSourceOperatorNodePushable.initialize(ConstantTupleSourceOperatorNodePushable.java:54) > at org.apache.hyracks.api.rewriter.runtime.SuperActivityOperatorNodePushable.initialize(SuperActivityOperatorNodePushable.java:85) > at org.apache.hyracks.control.nc.Task.run(Task.java:255) > ... 3 more > Caused by: org.apache.hyracks.algebricks.common.exceptions.AlgebricksException: java.lang.IllegalStateException: java.lang.ArrayIndexOutOfBoundsException: 16777216 > at org.apache.asterix.runtime.evaluators.functions.CastRecordDescriptor$2$1.evaluate(CastRecordDescriptor.java:95) > at org.apache.hyracks.algebricks.core.algebra.expressions.LogicalExpressionJobGenToExpressionRuntimeProviderAdapter$ScalarEvaluatorFactoryAdapter$1.evaluate(LogicalExpressionJobGenToExpressionRuntimeProviderAdapter.java:110) > at org.apache.hyracks.algebricks.runtime.operators.std.AssignRuntimeFactory$1.produceTuple(AssignRuntimeFactory.java:148) > ... 10 more > Caused by: java.lang.IllegalStateException: java.lang.ArrayIndexOutOfBoundsException: 16777216 > at org.apache.asterix.om.pointables.ARecordVisitablePointable.set(ARecordVisitablePointable.java:266) > at org.apache.asterix.om.pointables.AbstractVisitablePointable.set(AbstractVisitablePointable.java:59) > at org.apache.asterix.runtime.evaluators.functions.CastRecordDescriptor$2$1.evaluate(CastRecordDescriptor.java:90) > ... 12 more > Caused by: java.lang.ArrayIndexOutOfBoundsException: 16777216 > at org.apache.asterix.dataflow.data.nontagged.serde.AInt32SerializerDeserializer.getInt(AInt32SerializerDeserializer.java:50) > at org.apache.asterix.dataflow.data.nontagged.serde.ARecordSerializerDeserializer.getRecordLength(ARecordSerializerDeserializer.java:236) > at org.apache.asterix.om.util.NonTaggedFormatUtil.getFieldValueLength(NonTaggedFormatUtil.java:165) > at org.apache.asterix.om.pointables.ARecordVisitablePointable.set(ARecordVisitablePointable.java:219) > ... 14 more > at org.apache.asterix.test.aql.TestExecutor.executeHttpMethod(TestExecutor.java:218) > at org.apache.asterix.test.aql.TestExecutor.executeDDL(TestExecutor.java:312) > at org.apache.asterix.test.aql.TestExecutor.executeTest(TestExecutor.java:411) > at org.apache.asterix.test.runtime.ExecutionTest.test(ExecutionTest.java:170) > at sun.reflect.GeneratedMethodAccessor28.invoke(Unknown Source) > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:497) > at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) > at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) > at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) > at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) > at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) > at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) > at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) > at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) > at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) > at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) > at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) > at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) > at org.junit.runners.ParentRunner.run(ParentRunner.java:309) > at org.junit.runners.Suite.runChild(Suite.java:127) > at org.junit.runners.Suite.runChild(Suite.java:26) > at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) > at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) > at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) > at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) > at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) > at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) > at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) > at org.junit.runners.ParentRunner.run(ParentRunner.java:309) > at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) > at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) > at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) > at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) > at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) > at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) > ...Unexpected! -- This message was sent by Atlassian JIRA (v6.3.4#6332)