search

gklyne / milarq

Automatically exported from code.google.com/p/milarq
0 stars 0 forks source link

Repeated use of genericIndex property gives internal server error #7

Closed GoogleCodeExporter closed 9 years ago

GoogleCodeExporter commented 9 years ago 
This log shows the query and error.  The error report is similar to a previous 
issue with not enough variables for the index.  I think it's because 
genericIndex requires its subject to be a variable - i.e. must be used as a 
"generator", cannot be used as a "filter".

{{{
13:05:12 TRACE - zoo.sparqlite.Processor        :: [571] initialised request 
processor [http-8080-2]
13:05:12 INFO  - zoo.sparqlite.Processor        :: [571] begin processing 
request for path /combined
13:05:12 TRACE - zoo.sparqlite.Processor        :: [571] check if the endpoint 
exists [http-8080-2]
13:05:12 TRACE - zoo.sparqlite.Processor        :: [571] completed check 
endpoint exists in 0 ms [http-8080-2]
13:05:12 TRACE - zoo.sparqlite.Processor        :: [571] check the 
well-formedness of the request [http-8080-2]
13:05:12 TRACE - zoo.sparqlite.RequestWrapper   :: processing POST
13:05:12 TRACE - zoo.sparqlite.RequestWrapper   :: checking content type of 
POST request
13:05:12 TRACE - zoo.sparqlite.RequestWrapper   :: found content type: 
application/x-www-form-urlencoded; charset=UTF-8
13:05:12 TRACE - zoo.sparqlite.RequestWrapper   :: split by ';' first token: 
'application/x-www-form-urlencoded'
13:05:12 TRACE - zoo.sparqlite.RequestWrapper   :: content type ok
13:05:12 TRACE - zoo.sparqlite.RequestWrapper   :: found query param: PREFIX 
rdf: <http://www.w3.org/1999/02/22-rdf-syntax-ns#>
PREFIX rdfs: <http://www.w3.org/2000/01/rdf-schema#>
PREFIX crm: <http://purl.org/NET/crm-owl#>
PREFIX claros: <http://purl.org/NET/Claros/vocab#>
PREFIX pf:  <http://jena.hpl.hp.com/ARQ/property#>
PREFIX xsd:    <http://www.w3.org/2001/XMLSchema#>
SELECT * WHERE
{ GRAPH ?g
  {
    ?s claros:s-term-bysubject (  "CORINTH"  ) .
    ?s claros:s-term-bysubject (  "oinochoe"  ) .
    ?s crm:P14I.was_classified_by
        [ rdf:type crm:E17.Type_Assignment ;
          crm:P42.assigned
            [ a crm:E55.Type ; crm:P127.has_broader_term claros:Shape ; rdfs:label ?lit ]
        ] .
    FILTER regex( ?lit,  "oinochoe" , 'i')
    ?s claros:subject-findLocationName ?nam .
    FILTER regex( ?nam,  "CORINTH" , 'i')
  }
} OFFSET 5 LIMIT 5
13:05:12 TRACE - zoo.sparqlite.RequestWrapper   :: check query syntax
13:05:12 TRACE - zoo.sparqlite.RequestWrapper   :: using ARQ syntax
13:05:12 TRACE - zoo.sparqlite.RequestWrapper   :: parsed query ok
13:05:12 INFO  - zoo.sparqlite.Processor        :: [571] query: PREFIX rdf: 
<http://www.w3.org/1999/02/22-rdf-syntax-ns#>
PREFIX rdfs: <http://www.w3.org/2000/01/rdf-schema#>
PREFIX crm: <http://purl.org/NET/crm-owl#>
PREFIX claros: <http://purl.org/NET/Claros/vocab#>
PREFIX pf:  <http://jena.hpl.hp.com/ARQ/property#>
PREFIX xsd:    <http://www.w3.org/2001/XMLSchema#>
SELECT * WHERE
{ GRAPH ?g
  {
    ?s claros:s-term-bysubject (  "CORINTH"  ) .
    ?s claros:s-term-bysubject (  "oinochoe"  ) .
    ?s crm:P14I.was_classified_by
        [ rdf:type crm:E17.Type_Assignment ;
          crm:P42.assigned
            [ a crm:E55.Type ; crm:P127.has_broader_term claros:Shape ; rdfs:label ?lit ]
        ] .
    FILTER regex( ?lit,  "oinochoe" , 'i')
    ?s claros:subject-findLocationName ?nam .
    FILTER regex( ?nam,  "CORINTH" , 'i')
  }
} OFFSET 5 LIMIT 5
13:05:12 TRACE - zoo.sparqlite.Processor        :: [571] completed check 
request form in 2 ms [http-8080-2]
13:05:12 TRACE - zoo.sparqlite.Processor        :: [571] apply query policy 
rules [http-8080-2]
13:05:12 TRACE - zoo.sparqlite.QueryPolicy      :: apply query policy
13:05:12 TRACE - zoo.sparqlite.QueryPolicy      :: apply query form policy
13:05:12 TRACE - zoo.sparqlite.QueryPolicy      :: apply dataset description 
policy
13:05:12 TRACE - zoo.sparqlite.QueryPolicy      :: apply limit ceiling policy
13:05:12 TRACE - zoo.sparqlite.QueryPolicy      :: apply variable predicates 
policy
13:05:12 TRACE - zoo.sparqlite.QueryPolicy      :: no variable predicate policy 
found
13:05:12 TRACE - zoo.sparqlite.QueryPolicy      :: apply filter policy
13:05:12 TRACE - zoo.sparqlite.QueryPolicy      :: no variable predicate policy 
found
13:05:12 TRACE - zoo.sparqlite.Processor        :: [571] completed query policy 
in 0 ms [http-8080-2]
13:05:12 TRACE - zoo.sparqlite.Processor        :: [571] create a query 
execution [http-8080-2]
13:05:12 TRACE - zoo.sparqlite.EndpointTDB      :: exists guard condition
13:05:12 TRACE - sparqlite.config.Config        :: loading description model 
from 
/mnt/sdb3/home/graham/workspace35/.metadata/.plugins/org.eclipse.wst.server.core
/tmp1/wtpwebapps/claros_demo_server/WEB-INF/tdb/combined.ttl
13:05:12 TRACE - sparqlite.config.Config        :: description root is 
file:///mnt/sdb3/home/graham/workspace35/.metadata/.plugins/org.eclipse.wst.serv
er.core/tmp1/wtpwebapps/claros_demo_server/WEB-INF/tdb/combined.ttl#dataset
13:05:12 WARN  - jena.tdb.exec                  :: No BGP optimizer
13:05:12 TRACE - sparqlite.config.Config        :: description root is 
file:///mnt/sdb3/home/graham/workspace35/.metadata/.plugins/org.eclipse.wst.serv
er.core/tmp1/wtpwebapps/claros_demo_server/WEB-INF/tdb/combined.ttl#dataset
13:05:12 TRACE - sparqlite.config.Config        :: dataset root is 
file:///mnt/sdb3/home/graham/workspace35/.metadata/.plugins/org.eclipse.wst.serv
er.core/tmp1/wtpwebapps/claros_demo_server/WEB-INF/tdb/combined.ttl#dataset
13:05:12 TRACE - sparqlite.config.Config        :: LARQ location 
claros_demo_server/WebContent/WEB-INF/data/combined/lucene-literals provided
13:05:12 TRACE - zoo.sparqlite.EndpointTDB      :: create query execution
13:05:12 TRACE - zoo.sparqlite.EndpointTDB      :: set larq index
13:05:12 TRACE - zoo.sparqlite.Processor        :: [571] completed create query 
execution in 10 ms [http-8080-2]
13:05:12 TRACE - zoo.sparqlite.Processor        :: [571] execute the query 
[http-8080-2]
13:05:12 TRACE - zoo.sparqlite.Processor        :: [571] execute SELECT query 
[http-8080-2]
13:05:12 TRACE - propertyfunctions.genericIndex :: enableIndex: URI 
http://purl.org/NET/Claros/vocab#s-term-bysubject, term oinochoe
13:05:12 TRACE - propertyfunctions.genericIndex :: enableIndex: dir 
claros_demo_server/WebContent/WEB-INF/data/combined/indexes/term-subject
13:05:12 TRACE - propertyfunctions.genericIndex :: enableIndex: indexFullName 
claros_demo_server/WebContent/WEB-INF/data/combined/indexes/term-subject/oinocho
e
13:05:12 TRACE - propertyfunctions.genericIndex :: enableIndex: URI 
http://purl.org/NET/Claros/vocab#s-term-bysubject, term CORINTH
13:05:12 TRACE - propertyfunctions.genericIndex :: enableIndex: dir 
claros_demo_server/WebContent/WEB-INF/data/combined/indexes/term-subject
13:05:12 TRACE - propertyfunctions.genericIndex :: enableIndex: indexFullName 
claros_demo_server/WebContent/WEB-INF/data/combined/indexes/term-subject/corinth
13:05:12 ERROR - sparql.pfunction.PropertyFunctionBase$RepeatApplyIterator :: 
Fatal exception: Node: 
http://arachne.uni-koeln.de/artifact/36381-inschrift-fuer-augustus
13:05:12 ERROR - engine.iterator.QueryIterProcedure :: Fatal exception: Node: 
http://arachne.uni-koeln.de/artifact/36381-inschrift-fuer-augustus
13:05:12 TRACE - zoo.sparqlite.Processor        :: [571] handle an unexpected 
exception [http-8080-2]
13:05:12 ERROR - zoo.sparqlite.Processor        :: [571] caught unexpected 
exception: caught exception during query execution: Node: 
http://arachne.uni-koeln.de/artifact/36381-inschrift-fuer-augustus
com.hp.hpl.jena.sparql.core.Var$NotAVariableException: Node: 
http://arachne.uni-koeln.de/artifact/36381-inschrift-fuer-augustus
    at com.hp.hpl.jena.sparql.core.Var.alloc(Var.java:41)
    at propertyfunctions.genericIndex.execEvaluated(genericIndex.java:76)
    at com.hp.hpl.jena.sparql.pfunction.PropertyFunctionEval.exec(PropertyFunctionEval.java:32)
    at com.hp.hpl.jena.sparql.pfunction.PropertyFunctionBase$RepeatApplyIterator.nextStage(PropertyFunctionBase.java:92)
    at com.hp.hpl.jena.sparql.engine.iterator.QueryIterRepeatApply.makeNextStage(QueryIterRepeatApply.java:94)
    at com.hp.hpl.jena.sparql.engine.iterator.QueryIterRepeatApply.hasNextBinding(QueryIterRepeatApply.java:55)
    at com.hp.hpl.jena.sparql.engine.iterator.QueryIteratorBase.hasNext(QueryIteratorBase.java:69)
    at com.hp.hpl.jena.sparql.engine.iterator.QueryIterProcedure.hasNextBinding(QueryIterProcedure.java:57)
    at com.hp.hpl.jena.sparql.engine.iterator.QueryIteratorBase.hasNext(QueryIteratorBase.java:69)
    at com.hp.hpl.jena.tdb.solver.OpExecutorTDB.optimizeExecuteQuads(OpExecutorTDB.java:201)
    at com.hp.hpl.jena.tdb.solver.OpExecutorTDB.execute(OpExecutorTDB.java:148)
    at com.hp.hpl.jena.sparql.engine.main.ExecutionDispatch.visit(ExecutionDispatch.java:51)
    at com.hp.hpl.jena.sparql.algebra.op.OpQuadPattern.visit(OpQuadPattern.java:80)
    at com.hp.hpl.jena.sparql.engine.main.ExecutionDispatch.exec(ExecutionDispatch.java:33)
    at com.hp.hpl.jena.sparql.engine.main.OpExecutor.executeOp(OpExecutor.java:103)
    at com.hp.hpl.jena.sparql.engine.main.OpExecutor.execute(OpExecutor.java:186)
    at com.hp.hpl.jena.sparql.engine.main.ExecutionDispatch.visit(ExecutionDispatch.java:93)
    at com.hp.hpl.jena.sparql.algebra.op.OpSequence.visit(OpSequence.java:64)
    at com.hp.hpl.jena.sparql.engine.main.ExecutionDispatch.exec(ExecutionDispatch.java:33)
    at com.hp.hpl.jena.sparql.engine.main.OpExecutor.executeOp(OpExecutor.java:103)
    at com.hp.hpl.jena.sparql.engine.main.OpExecutor.execute(OpExecutor.java:263)
    at com.hp.hpl.jena.tdb.solver.OpExecutorTDB.execute(OpExecutorTDB.java:119)
    at com.hp.hpl.jena.sparql.engine.main.ExecutionDispatch.visit(ExecutionDispatch.java:143)
    at com.hp.hpl.jena.sparql.algebra.op.OpFilter.visit(OpFilter.java:80)
    at com.hp.hpl.jena.sparql.engine.main.ExecutionDispatch.exec(ExecutionDispatch.java:33)
    at com.hp.hpl.jena.sparql.engine.main.OpExecutor.executeOp(OpExecutor.java:103)
    at com.hp.hpl.jena.sparql.engine.main.OpExecutor.execute(OpExecutor.java:362)
    at com.hp.hpl.jena.sparql.engine.main.ExecutionDispatch.visit(ExecutionDispatch.java:241)
    at com.hp.hpl.jena.sparql.algebra.op.OpSlice.visit(OpSlice.java:39)
    at com.hp.hpl.jena.sparql.engine.main.ExecutionDispatch.exec(ExecutionDispatch.java:33)
    at com.hp.hpl.jena.sparql.engine.main.OpExecutor.executeOp(OpExecutor.java:103)
    at com.hp.hpl.jena.sparql.engine.main.OpExecutor.execute(OpExecutor.java:79)
    at com.hp.hpl.jena.sparql.engine.main.QC.execute(QC.java:40)
    at com.hp.hpl.jena.sparql.engine.main.QueryEngineMain.eval(QueryEngineMain.java:52)
    at com.hp.hpl.jena.tdb.solver.QueryEngineTDB.eval(QueryEngineTDB.java:112)
    at com.hp.hpl.jena.sparql.engine.QueryEngineBase.evaluate(QueryEngineBase.java:124)
    at com.hp.hpl.jena.sparql.engine.QueryEngineBase.createPlan(QueryEngineBase.java:98)
    at com.hp.hpl.jena.sparql.engine.QueryEngineBase.getPlan(QueryEngineBase.java:86)
    at com.hp.hpl.jena.tdb.solver.QueryEngineTDB$QueryEngineFactoryTDB.create(QueryEngineTDB.java:177)
    at com.hp.hpl.jena.sparql.engine.QueryExecutionBase.getPlan(QueryExecutionBase.java:266)
    at com.hp.hpl.jena.sparql.engine.QueryExecutionBase.startQueryIterator(QueryExecutionBase.java:243)
    at com.hp.hpl.jena.sparql.engine.QueryExecutionBase.execResultSet(QueryExecutionBase.java:248)
    at com.hp.hpl.jena.sparql.engine.QueryExecutionBase.execSelect(QueryExecutionBase.java:94)
    at uk.ac.ox.zoo.sparqlite.Processor.executeQuery(Processor.java:145)
    at uk.ac.ox.zoo.sparqlite.Processor.exec(Processor.java:64)
    at uk.ac.ox.zoo.sparqlite.ServletBase.doCommon(ServletBase.java:25)
    at uk.ac.ox.zoo.sparqlite.ServletTDB.doCommon(ServletTDB.java:55)
    at uk.ac.ox.zoo.sparqlite.ServletTDB.doPost(ServletTDB.java:47)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:637)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
    at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
    at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
    at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:433)
    at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
    at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
    at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
    at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:286)
    at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:845)
    at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
    at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
    at java.lang.Thread.run(Thread.java:619)
13:05:12 TRACE - zoo.sparqlite.Processor        :: [571] try to send error 
response, code: 500, details: caught exception during query execution: Node: 
http://arachne.uni-koeln.de/artifact/36381-inschrift-fuer-augustus [http-8080-2]
13:05:12 TRACE - zoo.sparqlite.Processor        :: [571] perform any final 
cleanup, release resources etc. [http-8080-2]
13:05:12 TRACE - zoo.sparqlite.Processor        :: [571] finalising request 
handler [http-8080-2]
13:05:12 TRACE - zoo.sparqlite.Processor        :: [571] close query execution 
[http-8080-2]
13:05:12 TRACE - zoo.sparqlite.Processor        :: [571] close endpoint 
[http-8080-2]
13:05:12 TRACE - zoo.sparqlite.EndpointTDB      :: close larq index
13:05:12 TRACE - zoo.sparqlite.EndpointTDB      :: close dataset
13:05:12 TRACE - zoo.sparqlite.Processor        :: [571] flush buffer 
[http-8080-2]
13:05:12 INFO  - zoo.sparqlite.Processor        :: [571] request processing 
completed in 42 ms
}}}

Original issue reported on code.google.com by gk-goo...@ninebynine.org on 17 Sep 2010 at 12:13

GoogleCodeExporter commented 9 years ago 
Ah!

Yes. I understand this. genericIndex requires that the subject be an UNBOUND
variable. Looks like that restriction needs to be lifted ...

Original comment by ehog.he...@googlemail.com on 17 Sep 2010 at 12:20

GoogleCodeExporter commented 9 years ago

Original comment by gk-goo...@ninebynine.org on 17 Sep 2010 at 2:23