Uploaded image for project: 'Blazegraph (by SYSTAP)'
  1. Blazegraph (by SYSTAP)
  2. BLZG-396

Rare exception caused by interrupt incorrectly reported through to SAIL by QueryEngine

    Details

      Description

      Interrupts are routinely used to terminate query processing when a LIMIT is satisfied. Interrupts can cause a number of exceptions, all of which qualify as "normal" termination for the query (or subquery). For example, InterruptedException, ClosedByInterruptException, or BufferClosedException. Since these exceptions are essentially normal termination, they should be quietly hidden by the QueryEngine rather than being reported out to the caller via IRunningQuery#get().

      The following stack trace shows a rare but repeatable problem where an interrupt will occasionally trigger a ClosedByInterruptException which is propagated all the way out to the SAIL. Depending on the timing of events (basically, whether the client has already closed the connection), the SPARQL client may or may not see an error while consuming the results.

           [java] ERROR: NanoSparqlServer.java:1255: org.openrdf.query.QueryEvaluationException: java.lang.RuntimeException: java.nio.channels.ClosedByInterruptException:
           [java] PREFIX bsbm-inst: <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/>
           [java] PREFIX bsbm: <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/vocabulary/>
           [java] PREFIX rdfs: <http://www.w3.org/2000/01/rdf-schema#>
           [java] PREFIX dc: <http://purl.org/dc/elements/1.1/>
           [java] SELECT ?label ?comment ?producer ?productFeature ?propertyTextual1 ?propertyTextual2 ?propertyTextual3
           [java]  ?propertyNumeric1 ?propertyNumeric2 ?propertyTextual4 ?propertyTextual5 ?propertyNumeric4 
           [java] WHERE {
           [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11063/Product560953> rdfs:label ?label .
           [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11063/Product560953> rdfs:comment ?comment .
           [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11063/Product560953> bsbm:producer ?p .
           [java]     ?p rdfs:label ?producer .
           [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11063/Product560953> dc:publisher ?p . 
           [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11063/Product560953> bsbm:productFeature ?f .
           [java]     ?f rdfs:label ?productFeature .
           [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11063/Product560953> bsbm:productPropertyTextual1 ?propertyTextual1 .
           [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11063/Product560953> bsbm:productPropertyTextual2 ?propertyTextual2 .
           [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11063/Product560953> bsbm:productPropertyTextual3 ?propertyTextual3 .
           [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11063/Product560953> bsbm:productPropertyNumeric1 ?propertyNumeric1 .
           [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11063/Product560953> bsbm:productPropertyNumeric2 ?propertyNumeric2 .
           [java]     OPTIONAL { <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11063/Product560953> bsbm:productPropertyTextual4 ?propertyTextual4 }
           [java]     OPTIONAL { <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11063/Product560953> bsbm:productPropertyTextual5 ?propertyTextual5 }
           [java]     OPTIONAL { <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11063/Product560953> bsbm:productPropertyNumeric4 ?propertyNumeric4 }
           [java] }
           [java] org.openrdf.query.QueryEvaluationException: org.openrdf.query.QueryEvaluationException: java.lang.RuntimeException: java.nio.channels.ClosedByInterruptException
           [java] 	at com.bigdata.rdf.sail.BigdataSailTupleQuery.evaluate(BigdataSailTupleQuery.java:54)
           [java] 	at org.openrdf.repository.sail.SailTupleQuery.evaluate(SailTupleQuery.java:62)
           [java] 	at com.bigdata.rdf.sail.bench.NanoSparqlServer$TupleQueryTask.doQuery(NanoSparqlServer.java:1297)
           [java] 	at com.bigdata.rdf.sail.bench.NanoSparqlServer$AbstractQueryTask.call(NanoSparqlServer.java:1250)
           [java] 	at com.bigdata.rdf.sail.bench.NanoSparqlServer$AbstractQueryTask.call(NanoSparqlServer.java:1187)
           [java] 	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
           [java] 	at java.util.concurrent.FutureTask.run(FutureTask.java:138)
           [java] 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
           [java] 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
           [java] 	at java.lang.Thread.run(Thread.java:619)
           [java] Caused by: org.openrdf.sail.SailException: org.openrdf.query.QueryEvaluationException: java.lang.RuntimeException: java.nio.channels.ClosedByInterruptException
           [java] 	at com.bigdata.rdf.sail.BigdataSail$BigdataSailConnection.evaluate(BigdataSail.java:3442)
           [java] 	at com.bigdata.rdf.sail.BigdataSailTupleQuery.evaluate(BigdataSailTupleQuery.java:47)
           [java] 	... 9 more
           [java] Caused by: org.openrdf.query.QueryEvaluationException: java.lang.RuntimeException: java.nio.channels.ClosedByInterruptException
           [java] 	at com.bigdata.rdf.sail.BigdataEvaluationStrategyImpl3.evaluateNatively(BigdataEvaluationStrategyImpl3.java:533)
           [java] 	at com.bigdata.rdf.sail.BigdataEvaluationStrategyImpl3.evaluate(BigdataEvaluationStrategyImpl3.java:487)
           [java] 	at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:473)
           [java] 	at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:164)
           [java] 	at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:384)
           [java] 	at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:342)
           [java] 	at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:161)
           [java] 	at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:369)
           [java] 	at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:161)
           [java] 	at com.bigdata.rdf.sail.BigdataEvaluationStrategyImpl3.evaluate(BigdataEvaluationStrategyImpl3.java:336)
           [java] 	at com.bigdata.rdf.sail.BigdataSail$BigdataSailConnection.evaluate(BigdataSail.java:3430)
           [java] 	... 10 more
           [java] Caused by: java.lang.RuntimeException: java.nio.channels.ClosedByInterruptException
           [java] 	at com.bigdata.rwstore.RWStore.getData(RWStore.java:1444)
           [java] 	at com.bigdata.rwstore.RWStore.getData(RWStore.java:1237)
           [java] 	at com.bigdata.journal.RWStrategy.readFromLocalStore(RWStrategy.java:615)
           [java] 	at com.bigdata.journal.RWStrategy.read(RWStrategy.java:132)
           [java] 	at com.bigdata.journal.AbstractJournal.read(AbstractJournal.java:2581)
           [java] 	at com.bigdata.btree.AbstractBTree.readNodeOrLeaf(AbstractBTree.java:3832)
           [java] 	at com.bigdata.btree.Node._getChild(Node.java:2706)
           [java] 	at com.bigdata.btree.AbstractBTree$1.compute(AbstractBTree.java:336)
           [java] 	at com.bigdata.btree.AbstractBTree$1.compute(AbstractBTree.java:321)
           [java] 	at com.bigdata.util.concurrent.Memoizer$1.call(Memoizer.java:79)
           [java] 	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
           [java] 	at java.util.concurrent.FutureTask.run(FutureTask.java:138)
           [java] 	at com.bigdata.util.concurrent.Memoizer.compute(Memoizer.java:87)
           [java] 	at com.bigdata.btree.AbstractBTree.loadChild(AbstractBTree.java:480)
           [java] 	at com.bigdata.btree.Node.getChild(Node.java:2617)
           [java] 	at com.bigdata.btree.Node.indexOf(Node.java:968)
           [java] 	at com.bigdata.btree.Node.indexOf(Node.java:985)
           [java] 	at com.bigdata.btree.Node.indexOf(Node.java:985)
           [java] 	at com.bigdata.btree.AbstractBTree.rangeCount(AbstractBTree.java:2551)
           [java] 	at com.bigdata.relation.accesspath.AccessPath.historicalRangeCount(AccessPath.java:1310)
           [java] 	at com.bigdata.relation.accesspath.AccessPath.rangeCount(AccessPath.java:1278)
           [java] 	at com.bigdata.bop.join.PipelineJoin$JoinTask$AccessPathTask.call(PipelineJoin.java:1598)
           [java] 	at com.bigdata.bop.join.PipelineJoin$JoinTask$BindingSetConsumerTask.executeTasks(PipelineJoin.java:1355)
           [java] 	at com.bigdata.bop.join.PipelineJoin$JoinTask$BindingSetConsumerTask.call(PipelineJoin.java:1055)
           [java] 	at com.bigdata.bop.join.PipelineJoin$JoinTask.consumeSource(PipelineJoin.java:830)
           [java] 	at com.bigdata.bop.join.PipelineJoin$JoinTask.call(PipelineJoin.java:723)
           [java] 	at com.bigdata.bop.join.PipelineJoin$JoinTask.call(PipelineJoin.java:500)
           [java] 	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
           [java] 	at java.util.concurrent.FutureTask.run(FutureTask.java:138)
           [java] 	at com.bigdata.bop.engine.ChunkedRunningQuery$ChunkTask.call(ChunkedRunningQuery.java:1260)
           [java] 	at com.bigdata.bop.engine.ChunkedRunningQuery$ChunkTaskWrapper.run(ChunkedRunningQuery.java:862)
           [java] 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
           [java] 	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
           [java] 	at java.util.concurrent.FutureTask.run(FutureTask.java:138)
           [java] 	at com.bigdata.bop.engine.ChunkedRunningQuery$ChunkFutureTask.run(ChunkedRunningQuery.java:789)
           [java] 	... 3 more
           [java] Caused by: java.nio.channels.ClosedByInterruptException
           [java] 	at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:184)
           [java] 	at sun.nio.ch.FileChannelImpl.read(FileChannelImpl.java:627)
           [java] 	at com.bigdata.io.FileChannelUtility.readAll(FileChannelUtility.java:188)
           [java] 	at com.bigdata.rwstore.RWStore.getData(RWStore.java:1401)
           [java] 	... 37 more
      

      This particular stack trace was produced during the "rampup" for the BSBM 200M data set.

        Activity

        Hide
        bryanthompson bryanthompson added a comment -

        Here is a related stack trace:

             [java] ERROR: ChunkedRunningQuery.java:903: queryId=69c0499d-6ad6-412d-9f3c-b308195eea5e, bopId=6, bop=com.bigdata.bop.join.PipelineJoin[6](PipelineJoin[4])[ com.bigdata.bop.BOp.bopId=6, com.bigdata.bop.join.PipelineJoin.select=[-const-7, -const-8, -const-9, p3, p1, testVar, label, -const-10, product, -const-5, -const-6, -const-3, -const-4, -const-1, -const-2], com.bigdata.bop.join.PipelineJoin.constraints=[com.bigdata.rdf.internal.constraints.CompareBOp(Var,Constant)[ com.bigdata.rdf.internal.constraints.CompareBOp.op=GT]], com.bigdata.bop.BOp.evaluationContext=ANY, com.bigdata.bop.join.PipelineJoin.predicate=com.bigdata.rdf.spo.SPOPredicate[7](product=null, TermId(446932U), p1=null)[ relationName=[BSBM_566496.spo], com.bigdata.bop.BOp.timestamp=1295202576315, com.bigdata.bop.IPredicate.flags=[KEYS,VALS,READONLY,PARALLEL], com.bigdata.bop.BOp.bopId=7, com.bigdata.rdf.sail.Rule2BOpUtility.originalIndex=SPO, com.bigdata.rdf.sail.Rule2BOpUtility.estimatedCardinality=566496]]
             [java] java.util.concurrent.CancellationException
             [java] 	at com.bigdata.util.concurrent.Haltable.get(Haltable.java:242)
             [java] 	at com.bigdata.bop.engine.QueryEngine.handleDoneQuery(QueryEngine.java:1089)
             [java] 	at com.bigdata.bop.engine.QueryEngine.getRunningQuery(QueryEngine.java:1025)
             [java] 	at com.bigdata.bop.engine.QueryEngine.startOp(QueryEngine.java:742)
             [java] 	at com.bigdata.bop.engine.ChunkedRunningQuery$ChunkTaskWrapper.run(ChunkedRunningQuery.java:854)
             [java] 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
             [java] 	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
             [java] 	at java.util.concurrent.FutureTask.run(FutureTask.java:138)
             [java] 	at com.bigdata.bop.engine.ChunkedRunningQuery$ChunkFutureTask.run(ChunkedRunningQuery.java:789)
             [java] 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
             [java] 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
             [java] 	at java.lang.Thread.run(Thread.java:619)
             [java] Caused by: java.lang.InterruptedException
             [java] 	at com.bigdata.util.concurrent.Haltable.cancel(Haltable.java:216)
             [java] 	at com.bigdata.bop.engine.AbstractRunningQuery.cancel(AbstractRunningQuery.java:838)
             [java] 	at com.bigdata.rdf.sail.RunningQueryCloseableIteration.close(RunningQueryCloseableIteration.java:43)
             [java] 	at info.aduna.iteration.Iterations.closeCloseable(Iterations.java:93)
             [java] 	at info.aduna.iteration.IterationWrapper.handleClose(IterationWrapper.java:101)
             [java] 	at info.aduna.iteration.FilterIteration.handleClose(FilterIteration.java:95)
             [java] 	at info.aduna.iteration.CloseableIterationBase.close(CloseableIterationBase.java:50)
             [java] 	at org.openrdf.query.algebra.evaluation.iterator.OrderIterator.createIteration(OrderIterator.java:121)
             [java] 	at info.aduna.iteration.DelayedIteration.hasNext(DelayedIteration.java:62)
             [java] 	at info.aduna.iteration.ConvertingIteration.hasNext(ConvertingIteration.java:62)
             [java] 	at info.aduna.iteration.IterationWrapper.hasNext(IterationWrapper.java:57)
             [java] 	at info.aduna.iteration.LimitIteration.hasNext(LimitIteration.java:62)
             [java] 	at org.openrdf.query.impl.TupleQueryResultImpl.hasNext(TupleQueryResultImpl.java:90)
             [java] 	at org.openrdf.query.QueryResultUtil.report(QueryResultUtil.java:46)
             [java] 	at org.openrdf.repository.sail.SailTupleQuery.evaluate(SailTupleQuery.java:63)
             [java] 	at com.bigdata.rdf.sail.bench.NanoSparqlServer$TupleQueryTask.doQuery(NanoSparqlServer.java:1297)
             [java] 	at com.bigdata.rdf.sail.bench.NanoSparqlServer$AbstractQueryTask.call(NanoSparqlServer.java:1250)
             [java] 	at com.bigdata.rdf.sail.bench.NanoSparqlServer$AbstractQueryTask.call(NanoSparqlServer.java:1187)
             [java] 	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
             [java] 	at java.util.concurrent.FutureTask.run(FutureTask.java:138)
             [java] 	... 3 more
        
        Show
        bryanthompson bryanthompson added a comment - Here is a related stack trace: [java] ERROR: ChunkedRunningQuery.java:903: queryId=69c0499d-6ad6-412d-9f3c-b308195eea5e, bopId=6, bop=com.bigdata.bop.join.PipelineJoin[6](PipelineJoin[4])[ com.bigdata.bop.BOp.bopId=6, com.bigdata.bop.join.PipelineJoin.select=[-const-7, -const-8, -const-9, p3, p1, testVar, label, -const-10, product, -const-5, -const-6, -const-3, -const-4, -const-1, -const-2], com.bigdata.bop.join.PipelineJoin.constraints=[com.bigdata.rdf.internal.constraints.CompareBOp(Var,Constant)[ com.bigdata.rdf.internal.constraints.CompareBOp.op=GT]], com.bigdata.bop.BOp.evaluationContext=ANY, com.bigdata.bop.join.PipelineJoin.predicate=com.bigdata.rdf.spo.SPOPredicate[7](product=null, TermId(446932U), p1=null)[ relationName=[BSBM_566496.spo], com.bigdata.bop.BOp.timestamp=1295202576315, com.bigdata.bop.IPredicate.flags=[KEYS,VALS,READONLY,PARALLEL], com.bigdata.bop.BOp.bopId=7, com.bigdata.rdf.sail.Rule2BOpUtility.originalIndex=SPO, com.bigdata.rdf.sail.Rule2BOpUtility.estimatedCardinality=566496]] [java] java.util.concurrent.CancellationException [java] at com.bigdata.util.concurrent.Haltable.get(Haltable.java:242) [java] at com.bigdata.bop.engine.QueryEngine.handleDoneQuery(QueryEngine.java:1089) [java] at com.bigdata.bop.engine.QueryEngine.getRunningQuery(QueryEngine.java:1025) [java] at com.bigdata.bop.engine.QueryEngine.startOp(QueryEngine.java:742) [java] at com.bigdata.bop.engine.ChunkedRunningQuery$ChunkTaskWrapper.run(ChunkedRunningQuery.java:854) [java] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441) [java] at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303) [java] at java.util.concurrent.FutureTask.run(FutureTask.java:138) [java] at com.bigdata.bop.engine.ChunkedRunningQuery$ChunkFutureTask.run(ChunkedRunningQuery.java:789) [java] at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [java] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [java] at java.lang.Thread.run(Thread.java:619) [java] Caused by: java.lang.InterruptedException [java] at com.bigdata.util.concurrent.Haltable.cancel(Haltable.java:216) [java] at com.bigdata.bop.engine.AbstractRunningQuery.cancel(AbstractRunningQuery.java:838) [java] at com.bigdata.rdf.sail.RunningQueryCloseableIteration.close(RunningQueryCloseableIteration.java:43) [java] at info.aduna.iteration.Iterations.closeCloseable(Iterations.java:93) [java] at info.aduna.iteration.IterationWrapper.handleClose(IterationWrapper.java:101) [java] at info.aduna.iteration.FilterIteration.handleClose(FilterIteration.java:95) [java] at info.aduna.iteration.CloseableIterationBase.close(CloseableIterationBase.java:50) [java] at org.openrdf.query.algebra.evaluation.iterator.OrderIterator.createIteration(OrderIterator.java:121) [java] at info.aduna.iteration.DelayedIteration.hasNext(DelayedIteration.java:62) [java] at info.aduna.iteration.ConvertingIteration.hasNext(ConvertingIteration.java:62) [java] at info.aduna.iteration.IterationWrapper.hasNext(IterationWrapper.java:57) [java] at info.aduna.iteration.LimitIteration.hasNext(LimitIteration.java:62) [java] at org.openrdf.query.impl.TupleQueryResultImpl.hasNext(TupleQueryResultImpl.java:90) [java] at org.openrdf.query.QueryResultUtil.report(QueryResultUtil.java:46) [java] at org.openrdf.repository.sail.SailTupleQuery.evaluate(SailTupleQuery.java:63) [java] at com.bigdata.rdf.sail.bench.NanoSparqlServer$TupleQueryTask.doQuery(NanoSparqlServer.java:1297) [java] at com.bigdata.rdf.sail.bench.NanoSparqlServer$AbstractQueryTask.call(NanoSparqlServer.java:1250) [java] at com.bigdata.rdf.sail.bench.NanoSparqlServer$AbstractQueryTask.call(NanoSparqlServer.java:1187) [java] at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303) [java] at java.util.concurrent.FutureTask.run(FutureTask.java:138) [java] ... 3 more
        Hide
        bryanthompson bryanthompson added a comment -

        The prior stack trace has been fixed. It was only appearing on the log, but not being thrown back to the client. However, the first stack trace remains an issue.

        Here is an updated version of the first stack trace. In this case, it appeared after 2783 presentations of query mixes against BSBM 200M. I think that perhaps the rampup and the long BSBM query mix trials are triggering this only once the database is sufficiently warm that the specific data race condition leading to this exception becomes more and more probable.

             [java] ERROR: NanoSparqlServer.java:1255: org.openrdf.query.QueryEvaluationException: java.lang.RuntimeException: java.nio.channels.ClosedByInterruptException:
             [java] PREFIX bsbm-inst: <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/>
             [java] PREFIX bsbm: <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/vocabulary/>
             [java] PREFIX rdfs: <http://www.w3.org/2000/01/rdf-schema#>
             [java] PREFIX dc: <http://purl.org/dc/elements/1.1/>
             [java] SELECT ?label ?comment ?producer ?productFeature ?propertyTextual1 ?propertyTextual2 ?propertyTextual3
             [java]  ?propertyNumeric1 ?propertyNumeric2 ?propertyTextual4 ?propertyTextual5 ?propertyNumeric4 
             [java] WHERE {
             [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> rdfs:label ?label .
             [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> rdfs:comment ?comment .
             [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:producer ?p .
             [java]     ?p rdfs:label ?producer .
             [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> dc:publisher ?p . 
             [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:productFeature ?f .
             [java]     ?f rdfs:label ?productFeature .
             [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:productPropertyTextual1 ?propertyTextual1 .
             [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:productPropertyTextual2 ?propertyTextual2 .
             [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:productPropertyTextual3 ?propertyTextual3 .
             [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:productPropertyNumeric1 ?propertyNumeric1 .
             [java]     <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:productPropertyNumeric2 ?propertyNumeric2 .
             [java]     OPTIONAL { <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:productPropertyTextual4 ?propertyTextual4 }
             [java]     OPTIONAL { <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:productPropertyTextual5 ?propertyTextual5 }
             [java]     OPTIONAL { <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:productPropertyNumeric4 ?propertyNumeric4 }
             [java] }
             [java] org.openrdf.query.QueryEvaluationException: org.openrdf.query.QueryEvaluationException: java.lang.RuntimeException: java.nio.channels.ClosedByInterruptException
             [java] 	at com.bigdata.rdf.sail.BigdataSailTupleQuery.evaluate(BigdataSailTupleQuery.java:54)
             [java] 	at org.openrdf.repository.sail.SailTupleQuery.evaluate(SailTupleQuery.java:62)
             [java] 	at com.bigdata.rdf.sail.bench.NanoSparqlServer$TupleQueryTask.doQuery(NanoSparqlServer.java:1297)
             [java] 	at com.bigdata.rdf.sail.bench.NanoSparqlServer$AbstractQueryTask.call(NanoSparqlServer.java:1250)
             [java] 	at com.bigdata.rdf.sail.bench.NanoSparqlServer$AbstractQueryTask.call(NanoSparqlServer.java:1187)
             [java] 	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
             [java] 	at java.util.concurrent.FutureTask.run(FutureTask.java:138)
             [java] 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
             [java] 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
             [java] 	at java.lang.Thread.run(Thread.java:619)
             [java] Caused by: org.openrdf.sail.SailException: org.openrdf.query.QueryEvaluationException: java.lang.RuntimeException: java.nio.channels.ClosedByInterruptException
             [java] 	at com.bigdata.rdf.sail.BigdataSail$BigdataSailConnection.evaluate(BigdataSail.java:3442)
             [java] 	at com.bigdata.rdf.sail.BigdataSailTupleQuery.evaluate(BigdataSailTupleQuery.java:47)
             [java] 	... 9 more
             [java] Caused by: org.openrdf.query.QueryEvaluationException: java.lang.RuntimeException: java.nio.channels.ClosedByInterruptException
             [java] 	at com.bigdata.rdf.sail.BigdataEvaluationStrategyImpl3.evaluateNatively(BigdataEvaluationStrategyImpl3.java:530)
             [java] 	at com.bigdata.rdf.sail.BigdataEvaluationStrategyImpl3.evaluate(BigdataEvaluationStrategyImpl3.java:486)
             [java] 	at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:473)
             [java] 	at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:164)
             [java] 	at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:384)
             [java] 	at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:342)
             [java] 	at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:161)
             [java] 	at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:369)
             [java] 	at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:161)
             [java] 	at com.bigdata.rdf.sail.BigdataEvaluationStrategyImpl3.evaluate(BigdataEvaluationStrategyImpl3.java:335)
             [java] 	at com.bigdata.rdf.sail.BigdataSail$BigdataSailConnection.evaluate(BigdataSail.java:3430)
             [java] 	... 10 more
             [java] Caused by: java.lang.RuntimeException: java.nio.channels.ClosedByInterruptException
             [java] 	at com.bigdata.rwstore.RWStore.getData(RWStore.java:1444)
             [java] 	at com.bigdata.rwstore.RWStore.getData(RWStore.java:1237)
             [java] 	at com.bigdata.journal.RWStrategy.readFromLocalStore(RWStrategy.java:615)
             [java] 	at com.bigdata.journal.RWStrategy.read(RWStrategy.java:132)
             [java] 	at com.bigdata.journal.AbstractJournal.read(AbstractJournal.java:2581)
             [java] 	at com.bigdata.btree.AbstractBTree.readNodeOrLeaf(AbstractBTree.java:3832)
             [java] 	at com.bigdata.btree.Node._getChild(Node.java:2706)
             [java] 	at com.bigdata.btree.AbstractBTree$1.compute(AbstractBTree.java:336)
             [java] 	at com.bigdata.btree.AbstractBTree$1.compute(AbstractBTree.java:321)
             [java] 	at com.bigdata.util.concurrent.Memoizer$1.call(Memoizer.java:79)
             [java] 	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
             [java] 	at java.util.concurrent.FutureTask.run(FutureTask.java:138)
             [java] 	at com.bigdata.util.concurrent.Memoizer.compute(Memoizer.java:87)
             [java] 	at com.bigdata.btree.AbstractBTree.loadChild(AbstractBTree.java:480)
             [java] 	at com.bigdata.btree.Node.getChild(Node.java:2617)
             [java] 	at com.bigdata.btree.Node.indexOf(Node.java:968)
             [java] 	at com.bigdata.btree.Node.indexOf(Node.java:985)
             [java] 	at com.bigdata.btree.Node.indexOf(Node.java:985)
             [java] 	at com.bigdata.btree.AbstractBTree.rangeCount(AbstractBTree.java:2551)
             [java] 	at com.bigdata.relation.accesspath.AccessPath.historicalRangeCount(AccessPath.java:1310)
             [java] 	at com.bigdata.relation.accesspath.AccessPath.rangeCount(AccessPath.java:1278)
             [java] 	at com.bigdata.bop.join.PipelineJoin$JoinTask$AccessPathTask.call(PipelineJoin.java:1598)
             [java] 	at com.bigdata.bop.join.PipelineJoin$JoinTask$BindingSetConsumerTask.executeTasks(PipelineJoin.java:1355)
             [java] 	at com.bigdata.bop.join.PipelineJoin$JoinTask$BindingSetConsumerTask.call(PipelineJoin.java:1055)
             [java] 	at com.bigdata.bop.join.PipelineJoin$JoinTask.consumeSource(PipelineJoin.java:830)
             [java] 	at com.bigdata.bop.join.PipelineJoin$JoinTask.call(PipelineJoin.java:723)
             [java] 	at com.bigdata.bop.join.PipelineJoin$JoinTask.call(PipelineJoin.java:500)
             [java] 	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
             [java] 	at java.util.concurrent.FutureTask.run(FutureTask.java:138)
             [java] 	at com.bigdata.bop.engine.ChunkedRunningQuery$ChunkTask.call(ChunkedRunningQuery.java:1254)
             [java] 	at com.bigdata.bop.engine.ChunkedRunningQuery$ChunkTaskWrapper.run(ChunkedRunningQuery.java:861)
             [java] 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
             [java] 	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
             [java] 	at java.util.concurrent.FutureTask.run(FutureTask.java:138)
             [java] 	at com.bigdata.bop.engine.ChunkedRunningQuery$ChunkFutureTask.run(ChunkedRunningQuery.java:788)
             [java] 	... 3 more
             [java] Caused by: java.nio.channels.ClosedByInterruptException
             [java] 	at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:184)
             [java] 	at sun.nio.ch.FileChannelImpl.read(FileChannelImpl.java:627)
             [java] 	at com.bigdata.io.FileChannelUtility.readAll(FileChannelUtility.java:188)
             [java] 	at com.bigdata.rwstore.RWStore.getData(RWStore.java:1401)
             [java] 	... 37 more
        
        Show
        bryanthompson bryanthompson added a comment - The prior stack trace has been fixed. It was only appearing on the log, but not being thrown back to the client. However, the first stack trace remains an issue. Here is an updated version of the first stack trace. In this case, it appeared after 2783 presentations of query mixes against BSBM 200M. I think that perhaps the rampup and the long BSBM query mix trials are triggering this only once the database is sufficiently warm that the specific data race condition leading to this exception becomes more and more probable. [java] ERROR: NanoSparqlServer.java:1255: org.openrdf.query.QueryEvaluationException: java.lang.RuntimeException: java.nio.channels.ClosedByInterruptException: [java] PREFIX bsbm-inst: <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/> [java] PREFIX bsbm: <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/vocabulary/> [java] PREFIX rdfs: <http://www.w3.org/2000/01/rdf-schema#> [java] PREFIX dc: <http://purl.org/dc/elements/1.1/> [java] SELECT ?label ?comment ?producer ?productFeature ?propertyTextual1 ?propertyTextual2 ?propertyTextual3 [java] ?propertyNumeric1 ?propertyNumeric2 ?propertyTextual4 ?propertyTextual5 ?propertyNumeric4 [java] WHERE { [java] <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> rdfs:label ?label . [java] <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> rdfs:comment ?comment . [java] <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:producer ?p . [java] ?p rdfs:label ?producer . [java] <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> dc:publisher ?p . [java] <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:productFeature ?f . [java] ?f rdfs:label ?productFeature . [java] <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:productPropertyTextual1 ?propertyTextual1 . [java] <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:productPropertyTextual2 ?propertyTextual2 . [java] <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:productPropertyTextual3 ?propertyTextual3 . [java] <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:productPropertyNumeric1 ?propertyNumeric1 . [java] <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:productPropertyNumeric2 ?propertyNumeric2 . [java] OPTIONAL { <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:productPropertyTextual4 ?propertyTextual4 } [java] OPTIONAL { <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:productPropertyTextual5 ?propertyTextual5 } [java] OPTIONAL { <http://www4.wiwiss.fu-berlin.de/bizer/bsbm/v01/instances/dataFromProducer11117/Product563718> bsbm:productPropertyNumeric4 ?propertyNumeric4 } [java] } [java] org.openrdf.query.QueryEvaluationException: org.openrdf.query.QueryEvaluationException: java.lang.RuntimeException: java.nio.channels.ClosedByInterruptException [java] at com.bigdata.rdf.sail.BigdataSailTupleQuery.evaluate(BigdataSailTupleQuery.java:54) [java] at org.openrdf.repository.sail.SailTupleQuery.evaluate(SailTupleQuery.java:62) [java] at com.bigdata.rdf.sail.bench.NanoSparqlServer$TupleQueryTask.doQuery(NanoSparqlServer.java:1297) [java] at com.bigdata.rdf.sail.bench.NanoSparqlServer$AbstractQueryTask.call(NanoSparqlServer.java:1250) [java] at com.bigdata.rdf.sail.bench.NanoSparqlServer$AbstractQueryTask.call(NanoSparqlServer.java:1187) [java] at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303) [java] at java.util.concurrent.FutureTask.run(FutureTask.java:138) [java] at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [java] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [java] at java.lang.Thread.run(Thread.java:619) [java] Caused by: org.openrdf.sail.SailException: org.openrdf.query.QueryEvaluationException: java.lang.RuntimeException: java.nio.channels.ClosedByInterruptException [java] at com.bigdata.rdf.sail.BigdataSail$BigdataSailConnection.evaluate(BigdataSail.java:3442) [java] at com.bigdata.rdf.sail.BigdataSailTupleQuery.evaluate(BigdataSailTupleQuery.java:47) [java] ... 9 more [java] Caused by: org.openrdf.query.QueryEvaluationException: java.lang.RuntimeException: java.nio.channels.ClosedByInterruptException [java] at com.bigdata.rdf.sail.BigdataEvaluationStrategyImpl3.evaluateNatively(BigdataEvaluationStrategyImpl3.java:530) [java] at com.bigdata.rdf.sail.BigdataEvaluationStrategyImpl3.evaluate(BigdataEvaluationStrategyImpl3.java:486) [java] at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:473) [java] at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:164) [java] at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:384) [java] at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:342) [java] at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:161) [java] at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:369) [java] at org.openrdf.query.algebra.evaluation.impl.EvaluationStrategyImpl.evaluate(EvaluationStrategyImpl.java:161) [java] at com.bigdata.rdf.sail.BigdataEvaluationStrategyImpl3.evaluate(BigdataEvaluationStrategyImpl3.java:335) [java] at com.bigdata.rdf.sail.BigdataSail$BigdataSailConnection.evaluate(BigdataSail.java:3430) [java] ... 10 more [java] Caused by: java.lang.RuntimeException: java.nio.channels.ClosedByInterruptException [java] at com.bigdata.rwstore.RWStore.getData(RWStore.java:1444) [java] at com.bigdata.rwstore.RWStore.getData(RWStore.java:1237) [java] at com.bigdata.journal.RWStrategy.readFromLocalStore(RWStrategy.java:615) [java] at com.bigdata.journal.RWStrategy.read(RWStrategy.java:132) [java] at com.bigdata.journal.AbstractJournal.read(AbstractJournal.java:2581) [java] at com.bigdata.btree.AbstractBTree.readNodeOrLeaf(AbstractBTree.java:3832) [java] at com.bigdata.btree.Node._getChild(Node.java:2706) [java] at com.bigdata.btree.AbstractBTree$1.compute(AbstractBTree.java:336) [java] at com.bigdata.btree.AbstractBTree$1.compute(AbstractBTree.java:321) [java] at com.bigdata.util.concurrent.Memoizer$1.call(Memoizer.java:79) [java] at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303) [java] at java.util.concurrent.FutureTask.run(FutureTask.java:138) [java] at com.bigdata.util.concurrent.Memoizer.compute(Memoizer.java:87) [java] at com.bigdata.btree.AbstractBTree.loadChild(AbstractBTree.java:480) [java] at com.bigdata.btree.Node.getChild(Node.java:2617) [java] at com.bigdata.btree.Node.indexOf(Node.java:968) [java] at com.bigdata.btree.Node.indexOf(Node.java:985) [java] at com.bigdata.btree.Node.indexOf(Node.java:985) [java] at com.bigdata.btree.AbstractBTree.rangeCount(AbstractBTree.java:2551) [java] at com.bigdata.relation.accesspath.AccessPath.historicalRangeCount(AccessPath.java:1310) [java] at com.bigdata.relation.accesspath.AccessPath.rangeCount(AccessPath.java:1278) [java] at com.bigdata.bop.join.PipelineJoin$JoinTask$AccessPathTask.call(PipelineJoin.java:1598) [java] at com.bigdata.bop.join.PipelineJoin$JoinTask$BindingSetConsumerTask.executeTasks(PipelineJoin.java:1355) [java] at com.bigdata.bop.join.PipelineJoin$JoinTask$BindingSetConsumerTask.call(PipelineJoin.java:1055) [java] at com.bigdata.bop.join.PipelineJoin$JoinTask.consumeSource(PipelineJoin.java:830) [java] at com.bigdata.bop.join.PipelineJoin$JoinTask.call(PipelineJoin.java:723) [java] at com.bigdata.bop.join.PipelineJoin$JoinTask.call(PipelineJoin.java:500) [java] at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303) [java] at java.util.concurrent.FutureTask.run(FutureTask.java:138) [java] at com.bigdata.bop.engine.ChunkedRunningQuery$ChunkTask.call(ChunkedRunningQuery.java:1254) [java] at com.bigdata.bop.engine.ChunkedRunningQuery$ChunkTaskWrapper.run(ChunkedRunningQuery.java:861) [java] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441) [java] at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303) [java] at java.util.concurrent.FutureTask.run(FutureTask.java:138) [java] at com.bigdata.bop.engine.ChunkedRunningQuery$ChunkFutureTask.run(ChunkedRunningQuery.java:788) [java] ... 3 more [java] Caused by: java.nio.channels.ClosedByInterruptException [java] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:184) [java] at sun.nio.ch.FileChannelImpl.read(FileChannelImpl.java:627) [java] at com.bigdata.io.FileChannelUtility.readAll(FileChannelUtility.java:188) [java] at com.bigdata.rwstore.RWStore.getData(RWStore.java:1401) [java] ... 37 more
        Hide
        bryanthompson bryanthompson added a comment -

        This issue has not resurfaced. I am going to close it out.

        Show
        bryanthompson bryanthompson added a comment - This issue has not resurfaced. I am going to close it out.

          People

          • Assignee:
            bryanthompson bryanthompson
            Reporter:
            bryanthompson bryanthompson
          • Votes:
            0 Vote for this issue
            Watchers:
            1 Start watching this issue

            Dates

            • Created:
              Updated:
              Resolved: