jena

jena copied to clipboard

Version

Apache Jena Fuseki 4.7.0-SNAPSHOT

What happened?

Occasionally (and apparently randomly), a "500 Server Error" is thrown.

The queries are persistent, common queries that work immediately after a restart, so I don't see problems with the query text.

It seems that, while the previous query reports "200 OK", a lock is not properly released on occasion.

• A race condition issue?
• A "GET" query followed by a "POST" query?
  • The "GET" query is a programmed query. The "POST" query is a Fuseki website query (http://localhost:3030/#/dataset/MyDS/query). Why would a POST after a successful GET indicate "Already in a transaction"?
• A "POST" query followed by a "POST" query? Needs Verification
  • These "POST" queries are all Fuseki website upload queries (http://localhost:3030/#/dataset/MyDS/upload). This is performed for initial graph loads on a new Fuseki data store.
  • This could be after a "List current graphs" query (http://localhost:3030/#/dataset/MyDS/edit), which I believe does a GET query.

Sorry there is not more to go on at the moment.

Relevant output and stacktrace

2022-10-18 10:59:41.801 INFO [qtp1984328477-44] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [10] GET http://localhost:3030/MyDS/sparql?query=PREFIX+...
2022-10-18 10:59:41.908 INFO [qtp1984328477-44] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [10] 200 OK (105 ms)
2022-10-18 11:00:12.332 INFO [qtp1984328477-51] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [11] GET http://localhost:3030/MyDS/sparql?query=PREFIX+...
2022-10-18 11:00:12.355 INFO [qtp1984328477-51] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [11] 200 OK (22 ms)
2022-10-18 11:00:23.876 INFO [qtp1984328477-51] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [12] POST http://localhost:3030/MyDS/query
2022-10-18 11:00:23.890 INFO [qtp1984328477-51] [org.apache.jena.fuseki.servlets.SPARQLQueryProcessor.execute(SPARQLQueryProcessor.java:248)] - [12] Query = PREFIX xsd: ...
2022-10-18 11:00:23.894 WARN [qtp1984328477-51] [org.apache.jena.atlas.logging.FmtLog.warn(FmtLog.java:113)] - [12] RC = 500 : Already in a transaction
org.apache.jena.sparql.JenaTransactionException: Already in a transaction
	at org.apache.jena.sparql.core.TransactionalLock.error(TransactionalLock.java:175) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
...
	at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1077) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at java.lang.Thread.run(Thread.java:829) ~[?:?]
2022-10-18 11:00:23.906 INFO [qtp1984328477-51] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [12] 500 Server Error (28 ms)

Are you interested in making a pull request?

Maybe

Oct 18 '22 21:10 AtesComp

Verifying the "POST" "POST issue: This occurs on a fresh Fuseki start with no other prior queries and no data (data directories deleted), so they are built empty.

Using Fuseki website to upload data files: select all files, upload all.

Even resetting to upload a single file selected as a single file fails the same way...I was getting 50/50 success/fail for awhile.

Example:

[2022-10-18 17:28:01] INFO [main] [org.apache.jena.fuseki.server.FusekiCoreInfo.lambda$infoPaths$0(FusekiCoreInfo.java:84)] - Path = /MyDS
[2022-10-18 17:28:01] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   Memory: 4.0 GiB
[2022-10-18 17:28:01] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   Java:   11.0.16
[2022-10-18 17:28:01] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   OS:     Linux 5.15.0-50-generic amd64
[2022-10-18 17:28:01] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   PID:    44287
[2022-10-18 17:28:01] INFO [main] [org.apache.jena.fuseki.cmd.JettyFusekiWebapp.start(JettyFusekiWebapp.java:130)] - Started 2022/10/18 17:28:01 EDT on port 3030
[2022-10-18 17:28:38] INFO [qtp1984328477-40] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [8] POST http://localhost:3030/MyDS/data
[2022-10-18 17:28:38] INFO [qtp1984328477-42] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [9] POST http://localhost:3030/MyDS/data
[2022-10-18 17:28:38] INFO [qtp1984328477-25] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [10] POST http://localhost:3030/MyDS/data
[2022-10-18 17:28:38] INFO [qtp1984328477-25] [org.apache.jena.fuseki.system.DataUploader.fileUploadMultipart(DataUploader.java:167)] - [10] Filename: MyDS_my_data1.trig, Content-Type=application/trig, Charset=null => TriG : Count=248 Triples=0 Quads=248
[2022-10-18 17:28:38] INFO [qtp1984328477-42] [org.apache.jena.fuseki.system.DataUploader.fileUploadMultipart(DataUploader.java:167)] - [9] Filename: MyDS_my_data2.trig, Content-Type=application/trig, Charset=null => TriG : Count=244 Triples=0 Quads=244
[2022-10-18 17:28:38] INFO [qtp1984328477-40] [org.apache.jena.fuseki.system.DataUploader.fileUploadMultipart(DataUploader.java:167)] - [8] Filename: MyDS_my_data3.trig, Content-Type=application/trig, Charset=null => TriG : Count=1907 Triples=0 Quads=1907
[2022-10-18 17:28:39] WARN [qtp1984328477-25] [org.apache.jena.atlas.logging.Log.warn(Log.java:54)] - Transaction still active - no commit or abort seen (forced abort)
[2022-10-18 17:28:39] WARN [qtp1984328477-25] [org.apache.jena.atlas.logging.Log.warn(Log.java:58)] - Exception in forced abort (trying to continue)
org.apache.jena.dboe.transaction.txn.TransactionException: Not in an active transaction
        at org.apache.jena.dboe.transaction.txn.TransactionalBase.checkActive(TransactionalBase.java:241) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
...
        at java.lang.Thread.run(Thread.java:829) ~[?:?]
[2022-10-18 17:28:39] WARN [qtp1984328477-25] [org.apache.jena.atlas.logging.Log.warn(Log.java:54)] - Transaction still active - no commit or abort seen (forced abort)
[2022-10-18 17:28:39] WARN [qtp1984328477-25] [org.apache.jena.atlas.logging.Log.warn(Log.java:58)] - Exception in forced abort (trying to continue)
org.apache.jena.dboe.transaction.txn.TransactionException: Not in an active transaction
        at org.apache.jena.dboe.transaction.txn.TransactionalBase.checkActive(TransactionalBase.java:241) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
...
        at java.lang.Thread.run(Thread.java:829) ~[?:?]
[2022-10-18 17:28:39] INFO [qtp1984328477-25] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [10] 500 Server Error (482 ms)
[2022-10-18 17:28:39] INFO [qtp1984328477-23] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [11] POST http://localhost:3030/MyDS/data
[2022-10-18 17:28:39] INFO [qtp1984328477-23] [org.apache.jena.fuseki.system.DataUploader.fileUploadMultipart(DataUploader.java:167)] - [11] Filename: MyDS_my_data4.trig, Content-Type=application/trig, Charset=null => TriG : Count=144 Triples=0 Quads=144

Oct 18 '22 21:10 AtesComp

The ... are important. I can't tell where in the code the exception is coming from.

What's the server dataset set (config.ttl)?

What date was the 4.7.0-SNAPSHOT?

Oct 19 '22 10:10 afs

More info as requested...

1. Comprehensive Log:

2022-10-18 12:07:37.142 INFO [qtp1984328477-27] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [9] 200 OK (76 ms)
2022-10-18 17:27:59.111 INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - Apache Jena Fuseki 4.7.0-SNAPSHOT 2022-10-18T17:27:59.103-04:00
2022-10-18 17:27:59.354 INFO [main] [org.apache.jena.fuseki.webapp.FusekiWebapp.formatBaseArea(FusekiWebapp.java:127)] - FUSEKI_HOME=/home/kates/Documents/Dev/CTIP/MyDS/fuseki/./apache-jena-fuseki-4.7.0-SNAPSHOT
2022-10-18 17:27:59.355 INFO [main] [org.apache.jena.fuseki.webapp.FusekiWebapp.formatBaseArea(FusekiWebapp.java:128)] - FUSEKI_BASE=/home/kates/Documents/Dev/CTIP/MyDS/fuseki/run
2022-10-18 17:27:59.405 INFO [main] [org.apache.jena.fuseki.webapp.ShiroEnvironmentLoader.customizeEnvironment(ShiroEnvironmentLoader.java:80)] - Shiro file: file:///home/kates/Documents/Dev/CTIP/MyDS/fuseki/run/shiro.ini
2022-10-18 17:28:01.759 INFO [main] [org.apache.jena.fuseki.server.FusekiCoreInfo.lambda$infoPaths$0(FusekiCoreInfo.java:84)] - Path = /MyDS
2022-10-18 17:28:01.763 INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   Memory: 4.0 GiB
2022-10-18 17:28:01.763 INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   Java:   11.0.16
2022-10-18 17:28:01.764 INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   OS:     Linux 5.15.0-50-generic amd64
2022-10-18 17:28:01.764 INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   PID:    44287
2022-10-18 17:28:01.825 INFO [main] [org.apache.jena.fuseki.cmd.JettyFusekiWebapp.start(JettyFusekiWebapp.java:130)] - Started 2022/10/18 17:28:01 EDT on port 3030
2022-10-18 17:28:38.534 INFO [qtp1984328477-40] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [8] POST http://localhost:3030/MyDS/data
2022-10-18 17:28:38.558 INFO [qtp1984328477-42] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [9] POST http://localhost:3030/MyDS/data
2022-10-18 17:28:38.588 INFO [qtp1984328477-25] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [10] POST http://localhost:3030/MyDS/data
2022-10-18 17:28:38.616 INFO [qtp1984328477-25] [org.apache.jena.fuseki.system.DataUploader.fileUploadMultipart(DataUploader.java:167)] - [10] Filename: MyDS_my_data1.trig, Content-Type=application/trig, Charset=null => TriG : Count=248 Triples=0 Quads=248
2022-10-18 17:28:38.617 INFO [qtp1984328477-42] [org.apache.jena.fuseki.system.DataUploader.fileUploadMultipart(DataUploader.java:167)] - [9] Filename: MyDS_my_data2.trig, Content-Type=application/trig, Charset=null => TriG : Count=244 Triples=0 Quads=244
2022-10-18 17:28:38.740 INFO [qtp1984328477-40] [org.apache.jena.fuseki.system.DataUploader.fileUploadMultipart(DataUploader.java:167)] - [8] Filename: MyDS_my_data3.trig, Content-Type=application/trig, Charset=null => TriG : Count=1907 Triples=0 Quads=1907
2022-10-18 17:28:39.053 WARN [qtp1984328477-25] [org.apache.jena.atlas.logging.Log.warn(Log.java:54)] - Transaction still active - no commit or abort seen (forced abort)
2022-10-18 17:28:39.054 WARN [qtp1984328477-25] [org.apache.jena.atlas.logging.Log.warn(Log.java:58)] - Exception in forced abort (trying to continue)
org.apache.jena.dboe.transaction.txn.TransactionException: Not in an active transaction
	at org.apache.jena.dboe.transaction.txn.TransactionalBase.checkActive(TransactionalBase.java:241) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.dboe.transaction.txn.TransactionalBase.abort(TransactionalBase.java:156) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.dboe.storage.system.DatasetGraphStorage.abort(DatasetGraphStorage.java:72) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.sparql.core.DatasetGraphWrapper.abort(DatasetGraphWrapper.java:281) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.dboe.storage.system.DatasetGraphTxnCtl.abort(DatasetGraphTxnCtl.java:153) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.sparql.core.TransactionHandlerView.abort(TransactionHandlerView.java:60) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.sparql.core.TxnDataset2Graph.lambda$abort$6(TxnDataset2Graph.java:210) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.sparql.core.TxnDataset2Graph.lambda$handlers$1(TxnDataset2Graph.java:163) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at java.util.HashMap.forEach(HashMap.java:1337) ~[?:?]
	at org.apache.jena.sparql.core.TxnDataset2Graph.handlers(TxnDataset2Graph.java:163) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.sparql.core.TxnDataset2Graph.abort(TxnDataset2Graph.java:210) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.sparql.core.DatasetGraphMapLink.abort(DatasetGraphMapLink.java:115) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.HttpAction.end(HttpAction.java:308) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.HttpAction.abortSilent(HttpAction.java:343) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.UploadRDF.quadsPutPostNonTxn(UploadRDF.java:195) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.GSP_RW.doPutPostQuads(GSP_RW.java:147) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.GSP_RW.execPostQuads(GSP_RW.java:79) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.GSP_RW.doPost(GSP_RW.java:72) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.ActionREST.doPost$(ActionREST.java:97) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.ActionREST.execute(ActionREST.java:46) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.ActionService.executeLifecycle(ActionService.java:58) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.ActionREST.execAny(ActionREST.java:160) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.ActionProcessor.execPost(ActionProcessor.java:48) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.ActionProcessor.process(ActionProcessor.java:34) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.ActionBase.process(ActionBase.java:54) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.ActionExecLib.execActionSub(ActionExecLib.java:124) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.ActionExecLib.execAction(ActionExecLib.java:98) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.server.Dispatcher.dispatchAction(Dispatcher.java:164) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.server.Dispatcher.process(Dispatcher.java:156) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.server.Dispatcher.dispatch(Dispatcher.java:83) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.FusekiFilter.doFilter(FusekiFilter.java:48) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:202) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1635) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.ProxiedFilterChain.doFilter(ProxiedFilterChain.java:61) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.AdviceFilter.executeChain(AdviceFilter.java:108) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.AdviceFilter.doFilterInternal(AdviceFilter.java:137) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:154) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.ProxiedFilterChain.doFilter(ProxiedFilterChain.java:66) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.AdviceFilter.executeChain(AdviceFilter.java:108) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.AdviceFilter.doFilterInternal(AdviceFilter.java:137) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:154) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.ProxiedFilterChain.doFilter(ProxiedFilterChain.java:66) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.AbstractShiroFilter.executeChain(AbstractShiroFilter.java:458) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.AbstractShiroFilter$1.call(AbstractShiroFilter.java:373) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.subject.support.SubjectCallable.doCall(SubjectCallable.java:90) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.subject.support.SubjectCallable.call(SubjectCallable.java:83) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.subject.support.DelegatingSubject.execute(DelegatingSubject.java:387) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.AbstractShiroFilter.doFilterInternal(AbstractShiroFilter.java:370) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:154) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:202) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1635) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.CrossOriginFilter.handle(CrossOriginFilter.java:344) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.CrossOriginFilter.doFilter(CrossOriginFilter.java:298) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:210) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1635) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:527) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:131) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:578) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:122) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:223) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1571) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:221) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1383) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:176) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:484) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1544) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:174) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1305) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:129) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:122) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:822) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:122) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.Server.handle(Server.java:563) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.HttpChannel.lambda$handle$0(HttpChannel.java:505) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:762) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:497) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:282) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:314) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:100) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.io.SelectableChannelEndPoint$1.run(SelectableChannelEndPoint.java:53) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.runTask(AdaptiveExecutionStrategy.java:421) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.consumeTask(AdaptiveExecutionStrategy.java:390) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.tryProduce(AdaptiveExecutionStrategy.java:277) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.produce(AdaptiveExecutionStrategy.java:199) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:933) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1077) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at java.lang.Thread.run(Thread.java:829) ~[?:?]
2022-10-18 17:28:39.063 WARN [qtp1984328477-25] [org.apache.jena.atlas.logging.Log.warn(Log.java:54)] - Transaction still active - no commit or abort seen (forced abort)
2022-10-18 17:28:39.064 WARN [qtp1984328477-25] [org.apache.jena.atlas.logging.Log.warn(Log.java:58)] - Exception in forced abort (trying to continue)
org.apache.jena.dboe.transaction.txn.TransactionException: Not in an active transaction
	at org.apache.jena.dboe.transaction.txn.TransactionalBase.checkActive(TransactionalBase.java:241) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.dboe.transaction.txn.TransactionalBase.abort(TransactionalBase.java:156) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.dboe.storage.system.DatasetGraphStorage.abort(DatasetGraphStorage.java:72) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.sparql.core.DatasetGraphWrapper.abort(DatasetGraphWrapper.java:281) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.dboe.storage.system.DatasetGraphTxnCtl.abort(DatasetGraphTxnCtl.java:153) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.sparql.core.TransactionHandlerView.abort(TransactionHandlerView.java:60) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.sparql.core.TxnDataset2Graph.lambda$abort$6(TxnDataset2Graph.java:210) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.sparql.core.TxnDataset2Graph.lambda$handlers$1(TxnDataset2Graph.java:163) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at java.util.HashMap.forEach(HashMap.java:1337) ~[?:?]
	at org.apache.jena.sparql.core.TxnDataset2Graph.handlers(TxnDataset2Graph.java:163) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.sparql.core.TxnDataset2Graph.abort(TxnDataset2Graph.java:210) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.sparql.core.DatasetGraphMapLink.abort(DatasetGraphMapLink.java:115) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.HttpAction.end(HttpAction.java:308) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.UploadRDF.quadsPutPostNonTxn(UploadRDF.java:198) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.GSP_RW.doPutPostQuads(GSP_RW.java:147) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.GSP_RW.execPostQuads(GSP_RW.java:79) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.GSP_RW.doPost(GSP_RW.java:72) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.ActionREST.doPost$(ActionREST.java:97) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.ActionREST.execute(ActionREST.java:46) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.ActionService.executeLifecycle(ActionService.java:58) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.ActionREST.execAny(ActionREST.java:160) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.ActionProcessor.execPost(ActionProcessor.java:48) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.ActionProcessor.process(ActionProcessor.java:34) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.ActionBase.process(ActionBase.java:54) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.ActionExecLib.execActionSub(ActionExecLib.java:124) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.ActionExecLib.execAction(ActionExecLib.java:98) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.server.Dispatcher.dispatchAction(Dispatcher.java:164) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.server.Dispatcher.process(Dispatcher.java:156) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.server.Dispatcher.dispatch(Dispatcher.java:83) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.FusekiFilter.doFilter(FusekiFilter.java:48) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:202) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1635) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.ProxiedFilterChain.doFilter(ProxiedFilterChain.java:61) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.AdviceFilter.executeChain(AdviceFilter.java:108) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.AdviceFilter.doFilterInternal(AdviceFilter.java:137) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:154) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.ProxiedFilterChain.doFilter(ProxiedFilterChain.java:66) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.AdviceFilter.executeChain(AdviceFilter.java:108) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.AdviceFilter.doFilterInternal(AdviceFilter.java:137) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:154) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.ProxiedFilterChain.doFilter(ProxiedFilterChain.java:66) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.AbstractShiroFilter.executeChain(AbstractShiroFilter.java:458) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.AbstractShiroFilter$1.call(AbstractShiroFilter.java:373) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.subject.support.SubjectCallable.doCall(SubjectCallable.java:90) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.subject.support.SubjectCallable.call(SubjectCallable.java:83) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.subject.support.DelegatingSubject.execute(DelegatingSubject.java:387) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.AbstractShiroFilter.doFilterInternal(AbstractShiroFilter.java:370) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:154) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:202) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1635) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.CrossOriginFilter.handle(CrossOriginFilter.java:344) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.apache.jena.fuseki.servlets.CrossOriginFilter.doFilter(CrossOriginFilter.java:298) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:210) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1635) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:527) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:131) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:578) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:122) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:223) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1571) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:221) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1383) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:176) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:484) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1544) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:174) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1305) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:129) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:122) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:822) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:122) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.Server.handle(Server.java:563) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.HttpChannel.lambda$handle$0(HttpChannel.java:505) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:762) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:497) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:282) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:314) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:100) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.io.SelectableChannelEndPoint$1.run(SelectableChannelEndPoint.java:53) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.runTask(AdaptiveExecutionStrategy.java:421) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.consumeTask(AdaptiveExecutionStrategy.java:390) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.tryProduce(AdaptiveExecutionStrategy.java:277) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.produce(AdaptiveExecutionStrategy.java:199) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:933) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1077) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
	at java.lang.Thread.run(Thread.java:829) ~[?:?]
2022-10-18 17:28:39.073 INFO [qtp1984328477-25] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [10] 500 Server Error (482 ms)
2022-10-18 17:28:39.203 INFO [qtp1984328477-23] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [11] POST http://localhost:3030/MyDS/data
2022-10-18 17:28:39.219 INFO [qtp1984328477-23] [org.apache.jena.fuseki.system.DataUploader.fileUploadMultipart(DataUploader.java:167)] - [11] Filename: MyDS_my_data4.trig, Content-Type=application/trig, Charset=null => TriG : Count=144 Triples=0 Quads=144

2. Fuseki Config File

# Licensed under the terms of http://www.apache.org/licenses/LICENSE-2.0

## Fuseki Server configuration file.

## Reasoners:
##   GenericRuleReasoner -> <http://jena.hpl.hp.com/2003/GenericRuleReasoner>
##   OWLMicroReasoner    -> <http://jena.hpl.hp.com/2003/OWLMicroFBRuleReasoner>
##   OWLMiniReasoner     -> <http://jena.hpl.hp.com/2003/OWLMiniFBRuleReasoner>
##   RDFSRuleReasoner    -> <http://jena.hpl.hp.com/2003/RDFSExptRuleReasoner>
##   OWLFBRuleReasoner   -> <http://jena.hpl.hp.com/2003/OWLFBRuleReasoner>
##   TransitiveReasoner  -> <http://jena.hpl.hp.com/2003/TransitiveReasoner>

@prefix :        <#> .
@prefix fuseki:  <http://jena.apache.org/fuseki#> .
@prefix rdf:     <http://www.w3.org/1999/02/22-rdf-syntax-ns#> .
@prefix rdfs:    <http://www.w3.org/2000/01/rdf-schema#> .
@prefix ja:      <http://jena.hpl.hp.com/2005/11/Assembler#> .
@prefix tdb2:    <http://jena.apache.org/2016/tdb#> .
@prefix res:     <http://jena.hpl.hp.com/2003/> .
@prefix gnr:     <https://gnr.org/> .
@prefix mydata:  <https://mydata.org/> .

## ------------------------------------------------------------
## Server

[] a fuseki:Server ;
    rdfs:label "MyData Server" ;
    ja:context [ ja:cxtName "arq:queryTimeout" ;  ja:cxtValue "30000,60000" ] ;
    ##  Services
    fuseki:services ( :serviceMyData ) ;
    .

## ------------------------------------------------------------
## Service Descriptions

:serviceMyData a fuseki:Service ;
    rdfs:label "TDB2 MyData Dataset" ;
    fuseki:name "MyDS" ;
    fuseki:dataset :dsMyData ;
    fuseki:endpoint [ fuseki:name "sparql" ; fuseki:operation fuseki:query  ] ;
    fuseki:endpoint [ fuseki:name "query"  ; fuseki:operation fuseki:query  ] ;
    fuseki:endpoint [ fuseki:name "update" ; fuseki:operation fuseki:update ] ;
    fuseki:endpoint [ fuseki:name "upload" ; fuseki:operation fuseki:upload ] ;
    fuseki:endpoint [ fuseki:name "get"    ; fuseki:operation fuseki:gsp_r  ] ;
    fuseki:endpoint [ fuseki:name "data"   ; fuseki:operation fuseki:gsp_rw ] ;
    fuseki:endpoint [ fuseki:operation fuseki:gsp_rw ] ;
    .

## ------------------------------------------------------------
## MyData Dataset

:dsMyData a ja:RDFDataset ;
    rdfs:label "MyData RDF Dataset" ;
    ja:defaultGraph :graphInfMyData ;
    ja:namedGraph [ ja:graphName mydata:stage         ; ja:graph :graphDefault    ] ;
    ja:namedGraph [ ja:graphName mydata:configuration ; ja:graph :graphMyData_Conf  ] ;
    ja:namedGraph [ ja:graphName mydata:capability    ; ja:graph :graphMyData_Cap   ] ;
    ja:namedGraph [ ja:graphName mydata:category      ; ja:graph :graphMyData_Cat   ] ;
    ja:namedGraph [ ja:graphName gnr:people           ; ja:graph :graphGNR_People ] ;
    ja:namedGraph [ ja:graphName gnr:organization     ; ja:graph :graphGNR_Org    ] ;

    ja:namedGraph [ ja:graphName gnr:vcard            ; ja:graph :graphGNR_VCard  ] ;
    ja:namedGraph [ ja:graphName gnr:ontology         ; ja:graph :graphGNR_Ont    ] ;
#    ja:namedGraph [ ja:graphName mydata:inference     ; ja:graph :graphInfMyData    ] ;
    .

## ------------------------------------------------------------
## Inference Model

:graphInfMyData a ja:InfModel ;
    rdfs:label "MyData Inference Model" ;
    ja:baseModel :modelUnionMyData ;
    ja:reasoner :reasonerMyData ;
    .

:reasonerMyData a ja:Reasoner ;
    ja:reasonerURL res:OWLMicroFBRuleReasoner ;
    ja:schema :graphGNR_Ont ;
    ja:schema :graphGNR_VCard ;
    .

## ------------------------------------------------------------
## Union Model

:modelUnionMyData a ja:UnionModel ;
    rdfs:label "MyData Union Model" ;
    ja:rootModel :graphDefault;
    ja:subModel :graphMyData_Cap ;
    ja:subModel :graphMyData_Cat ;
    ja:subModel :graphGNR_People ;
    ja:subModel :graphGNR_Org ;
    .

## ------------------------------------------------------------
## Default Graph

:graphDefault a tdb2:GraphTDB2 ;
    rdfs:label "Default Graph" ;
    tdb2:dataset :ds_tdb2_MyData ;
    .

## ------------------------------------------------------------
## MyData Capability Graph

:graphMyData_Cap a tdb2:GraphTDB2 ;
    rdfs:label "MyData Capability Graph" ;
    tdb2:graphName mydata:capability ;
    tdb2:dataset :ds_tdb2_MyData ;
    .

## ------------------------------------------------------------
## MyData Configuration Graph

:graphMyData_Conf a tdb2:GraphTDB2 ;
    rdfs:label "MyData Configuration Graph" ;
    tdb2:graphName mydata:configuration ;
    tdb2:dataset :ds_tdb2_MyData ;gnr
    .

## ------------------------------------------------------------
## MyData Categories Graph

:graphMyData_Cat a tdb2:GraphTDB2 ;
    rdfs:label "MyData Category Graph" ;
    tdb2:graphName mydata:category ;
    tdb2:dataset :ds_tdb2_MyData ;
    .

## ------------------------------------------------------------
## GNR People Graph

:graphGNR_People a tdb2:GraphTDB2 ;
    rdfs:label "GNR People Graph" ;
    tdb2:graphName gnr:people ;
    tdb2:dataset :ds_tdb2_MyData ;
    .

## ------------------------------------------------------------
## GNR Organization Graph

:graphGNR_Org a tdb2:GraphTDB2 ;
    rdfs:label "GNR Organization Graph" ;
    tdb2:graphName gnr:organization ;
    tdb2:dataset :ds_tdb2_MyData ;
    .

## ------------------------------------------------------------
## GNR VCard Ontology Graph

:graphGNR_VCard a tdb2:GraphTDB2 ;
    rdfs:label "GNR VCard Ontology Graph" ;
    tdb2:graphName gnr:vcard ;
    tdb2:dataset :ds_tdb2_MyData ;
    .

## ------------------------------------------------------------
## GNR Ontology Graph

:graphGNR_Ont a tdb2:GraphTDB2 ;
    rdfs:label "GNR Ontology Graph" ;
    tdb2:graphName gnr:ontology ;
    tdb2:dataset :ds_tdb2_MyData ;
    .

## ------------------------------------------------------------
## The Database

:ds_tdb2_MyData a tdb2:DatasetTDB2 ;
    rdfs:label "MyData TDB2 Dataset" ;
    tdb2:location "./data" ;
    .

## ------------------------------------------------------------
# End triples.

3. 4.7.0-SNAPSHOT Date My tar.gz says 10-14-2022

Oct 20 '22 18:10 AtesComp

Apparently, switching from the OWLMicroFBRuleReasoner to the OWLMiniFBRuleReasoner make the problem worse...even when queries are not using the inference graph. I did this because "micro" didn't infer from owl:sameAs, while "mini" did.

Also (and probably a separate problem), at least with my data, using FROM or FROM NAMED tends to to cause additional query problems over using GRAPH for simple queries. FROM will timeout while GRAPH succeeds if a 500 doesn't happen.

The graphs are relatively small:

When using OWLMini, I can't get the inferred count as it surpasses the timeout (30 seconds?) using the Fuseki web interface for info.

Oct 22 '22 03:10 AtesComp

In both cases the transaction is connected to a TDB2 transaction which is why an error on one graph affects everything layered over TDB2.

Oct 22 '22 15:10 afs

@afs Taking a hint, I tried putting each graph in its own TDB2 dataset to separate the transactions and test. Example:

## ------------------------------------------------------------
## MyData Configuration Graph

:graphMyData_Conf a tdb2:GraphTDB2 ;
    rdfs:label "MyData Configuration Graph" ;
    tdb2:graphName mydata:configuration ;
    tdb2:dataset :ds_tdb2_MyData_Conf ;
    .

## Configuration Dataset

:ds_tdb2_MyData_Conf a tdb2:DatasetTDB2 ;
    rdfs:label "MyData Configuration TDB2 Dataset" ;
    tdb2:location "./data/configuration" ;
    .

After running two or three queries against these independent graphs, I consistently get errors. However, it will "recover" after some time and let me do the query. Example:

[2022-10-23 13:33:21.212] INFO [qtp2081964843-21] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [12] POST http://localhost:3030/MyData/sparql
[2022-10-23 13:33:21.213] INFO [qtp2081964843-21] [org.apache.jena.fuseki.servlets.SPARQLQueryProcessor.execute(SPARQLQueryProcessor.java:248)] - [12] Query = PREFIX rdf: <http://www.w3.org/1999/02/22-rdf-syntax-ns#> PREFIX rdfs: <http://www.w3.org/2000/01/rdf-schema#>    SELECT * FROM <https://mydata.org/configuration> WHERE {     ?sub ?pred ?obj . }
[2022-10-23 13:33:21.216] WARN [qtp2081964843-21] [org.apache.jena.atlas.logging.FmtLog.warn(FmtLog.java:113)] - [12] RC = 500 : Already in a transaction
org.apache.jena.sparql.JenaTransactionException: Already in a transaction
        at org.apache.jena.sparql.core.TransactionalLock.error(TransactionalLock.java:175) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.sparql.core.TransactionalLock.begin(TransactionalLock.java:104) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.sparql.core.TxnDataset2Graph.begin(TxnDataset2Graph.java:190) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.sparql.core.DatasetGraphMapLink.begin(DatasetGraphMapLink.java:112) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.fuseki.servlets.HttpAction.begin(HttpAction.java:276) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.fuseki.servlets.HttpAction.beginRead(HttpAction.java:291) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.fuseki.servlets.SPARQLQueryProcessor.execute(SPARQLQueryProcessor.java:271) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.fuseki.servlets.SPARQLQueryProcessor.executeWithParameter(SPARQLQueryProcessor.java:224) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.fuseki.servlets.SPARQLQueryProcessor.execute(SPARQLQueryProcessor.java:209) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.fuseki.servlets.ActionService.executeLifecycle(ActionService.java:58) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.fuseki.servlets.SPARQLQueryProcessor.execPost(SPARQLQueryProcessor.java:84) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.fuseki.servlets.ActionProcessor.process(ActionProcessor.java:34) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.fuseki.servlets.ActionBase.process(ActionBase.java:54) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.fuseki.servlets.ActionExecLib.execActionSub(ActionExecLib.java:124) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.fuseki.servlets.ActionExecLib.execAction(ActionExecLib.java:98) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.fuseki.server.Dispatcher.dispatchAction(Dispatcher.java:164) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.fuseki.server.Dispatcher.process(Dispatcher.java:156) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.fuseki.server.Dispatcher.dispatch(Dispatcher.java:83) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.fuseki.servlets.FusekiFilter.doFilter(FusekiFilter.java:48) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:202) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1635) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.shiro.web.servlet.ProxiedFilterChain.doFilter(ProxiedFilterChain.java:61) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.shiro.web.servlet.AdviceFilter.executeChain(AdviceFilter.java:108) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.shiro.web.servlet.AdviceFilter.doFilterInternal(AdviceFilter.java:137) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:154) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.shiro.web.servlet.ProxiedFilterChain.doFilter(ProxiedFilterChain.java:66) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.shiro.web.servlet.AdviceFilter.executeChain(AdviceFilter.java:108) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.shiro.web.servlet.AdviceFilter.doFilterInternal(AdviceFilter.java:137) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:154) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.shiro.web.servlet.ProxiedFilterChain.doFilter(ProxiedFilterChain.java:66) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.shiro.web.servlet.AbstractShiroFilter.executeChain(AbstractShiroFilter.java:458) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.shiro.web.servlet.AbstractShiroFilter$1.call(AbstractShiroFilter.java:373) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.shiro.subject.support.SubjectCallable.doCall(SubjectCallable.java:90) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.shiro.subject.support.SubjectCallable.call(SubjectCallable.java:83) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.shiro.subject.support.DelegatingSubject.execute(DelegatingSubject.java:387) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.shiro.web.servlet.AbstractShiroFilter.doFilterInternal(AbstractShiroFilter.java:370) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:154) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:202) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1635) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.fuseki.servlets.CrossOriginFilter.handle(CrossOriginFilter.java:344) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.apache.jena.fuseki.servlets.CrossOriginFilter.doFilter(CrossOriginFilter.java:298) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:210) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1635) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:527) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:131) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:578) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:122) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:223) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1571) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:221) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1383) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:176) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:484) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1544) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:174) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1305) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:129) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:122) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:822) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:122) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.server.Server.handle(Server.java:563) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.server.HttpChannel.lambda$handle$0(HttpChannel.java:505) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:762) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:497) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:282) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:314) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:100) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.io.SelectableChannelEndPoint$1.run(SelectableChannelEndPoint.java:53) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.runTask(AdaptiveExecutionStrategy.java:421) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.consumeTask(AdaptiveExecutionStrategy.java:390) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.tryProduce(AdaptiveExecutionStrategy.java:277) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.lambda$new$0(AdaptiveExecutionStrategy.java:139) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:411) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:933) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1077) ~[fuseki-server.jar:4.7.0-SNAPSHOT]
        at java.lang.Thread.run(Thread.java:829) ~[?:?]
[2022-10-23 13:33:21.223] INFO [qtp2081964843-21] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [12] 500 Server Error (10 ms)

Oct 23 '22 17:10 AtesComp

FYI:

Jena:       VERSION: 4.7.0-SNAPSHOT
Jena:       BUILD_DATE: 2022-10-20T18:00:34Z
TDB:        VERSION: 4.7.0-SNAPSHOT
TDB:        BUILD_DATE: 2022-10-20T18:00:34Z
Fuseki:     VERSION: 4.7.0-SNAPSHOT
Fuseki:     BUILD_DATE: ${build.time.xsd}

Oct 23 '22 18:10 AtesComp

So that configuration on it's own causes the problem "Already in a transaction"? (I'm trying to find a small example to debug)

Oct 23 '22 19:10 afs

Apparently so.

I did some additional work and it looked like the ja:schema may have been partially suspect. I went back to 4.6.1 and adjusted the config to remove the ja:UnionModel and used a tdb2:unionDefaultGraph instead like so:

...
    fuseki:endpoint [ fuseki:name "sparql" ; fuseki:operation fuseki:query ;
        ## Set default-union-graph for this endpoint.
        ja:context [ ja:cxtName "tdb2:unionDefaultGraph" ; ja:cxtValue true ] ; ] ;
...
:graphInfMyData ja:InfModel ;
    rdfs:label "MyData Inference Model" ;
    ja:baseModel :graphDefault ;
    ja:reasoner :reasonerMyData ;
    .
...
<<< Remove :modelUnionMyData <<<
...
:graphDefault a tdb2:GraphTDB2 ;
    rdfs:label "Default Graph" ;
    tdb2:graphName "urn:x-arq:UnionGraph" ;
    tdb2:dataset :ds_tdb2_MyData;
    .

This left the Reasoner intact:

:reasonerMyData a ja:Reasoner ;
    ja:reasonerURL res:OWLMicroFBRuleReasoner ;
    ja:schema :graphGNR_Ont ;
    ja:schema :graphGNR_VCard ;
    .

On startup, I got an error immediately after the Shiro load:

[2022-10-24 14:33:19.738] ERROR [main] [org.apache.jena.fuseki.webapp.FusekiServerListener.serverInitialization(FusekiServerListener.java:106)] - Exception in initialization: caught: Not in a transaction
[2022-10-24 14:33:19.738] WARN [main] [org.eclipse.jetty.webapp.WebAppContext.doStart(WebAppContext.java:541)] - Failed startup of context...

When I removed the ja:schema statements, it all worked well for 4.6.1:

...
:reasonerCOLT a ja:Reasoner ;
    ja:reasonerURL res:OWLMicroFBRuleReasoner ;
#    ja:schema :graphGNR_Ont ;
#    ja:schema :graphGNR_VCard ;
    .
...

As I was writing this up, I decided to test 4.7.0-SNAPSHOT as well.

1. Using 4.7.0-SNAPSHOT with the previous config and only moving the ja:schema statements to ja:subModel statements in the ja:UnionModel didn't help as much as I thought it might--I still got 500 errors after using the UI Edit to view a few graphs.
2. Using 4.7.0-SNAPSHOT with the updated config (above) seems to work well. I couldn't break it with my limited testing.

Also, I've tested this issue on multiple machines with different environments, so it's not just due to one machine's peculiar settings.

It seems a ja:UnionModel is the ultimate culprit.

By using the config with the changes above, the resulting inference on the union default graph adds more graphs than I want. I can live with it for now.

Oct 24 '22 19:10 AtesComp

There are multiple issues.

https://github.com/apache/jena/pull/1573 (accidently !!) fixes some of the problems.

I have a setup that causes "Not in a transaction" in 4.6.1 and works in 4.7.0-SNAPSHOT (Oct 27) with ja:schema graphs which is a start-up error.

I can also reproduce something for "Already in a transaction" for a query time error for 4.6.1 but which works for 4.7.0-SNAPSHOT.

So lets work with the current 4.7.0-SNAPSHOT.

This issue has got quite complicated so I'm not sure I am recreating your setup correctly. Please could you provide a complete config.ttl which causes a problem when using 4.7.0-SNAPSHOT (current).

Oct 27 '22 14:10 afs

Ping.

We'll assume that everything mentioned works using the development codebase for the next release - 4.7.0 - unless we hear otherwise.

Nov 09 '22 18:11 afs

Sorry, I've been covered up in work lately. I'll get back to this and test. Thanks for the update.

Nov 10 '22 02:11 AtesComp

Finally back... ...and there are issues...

Initialize:

I loaded the data using the tdb2.tdbloader --tdb ... command in the apache-jena-4.7.0-SNAPSHOT/bin directory. All went well.

The configuration is nearly the same as before. The only difference from the previous config is that I uncommented the ja:namedGraph line for the inference graph and added a new "Tags" graph that I separated from the main data.

Then started Fuseki:

Apache Jena Fuseki
Version 4.7.0-SNAPSHOT. Uptime 13m 32s

and

[2022-11-21 11:08:06.128] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - Apache Jena Fuseki 4.7.0-SNAPSHOT 2022-11-21T14:53:09Z

First Test:

1. I did a simple query on the dataset from the SPARQL Query screen. All went well.
2. I then did info -> count triples in all graphs. All went well.
3. I then went to edit -> list current graphs and encountered a 500 : Already in a transaction error.

All other queries continued to work, i.e., going back to the SPARQL Query page, doing info -> count triples in all graphs maybe because they were cached. Attempting to do the edit -> list current graphs always failed.

Second Test:

I then restarted Fuseki and went directly to edit -> list current graphs and the query worked. However, all other queries failed for some time after.

After waiting a few minutes, the other queries began to work again.

Third Test:

I then started to use my application on the data to test general response. The first two queries worked. Others thereafter had 500 Server Errors.

I waited quite a while to see if it cleared up, but it continues to give me 500 : Already in a transaction errors

Note:

I will attempt to simplify the dataset down to one that I can share and continues to show the error.

Below, I've documented the startup sequence and the first test error.

Startup Log:

The following log is to document the Fuseki start up process.

[2022-11-21 11:08:06.128] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - Apache Jena Fuseki 4.7.0-SNAPSHOT 2022-11-21T14:53:09Z
[2022-11-21 11:08:06.499] INFO [main] [org.apache.jena.fuseki.webapp.FusekiWebapp.formatBaseArea(FusekiWebapp.java:127)] - FUSEKI_HOME=/home/...<redacted>.../MyData/fuseki/./apache-jena-fuseki-4.7.0-SNAPSHOT
[2022-11-21 11:08:06.501] INFO [main] [org.apache.jena.fuseki.webapp.FusekiWebapp.formatBaseArea(FusekiWebapp.java:128)] - FUSEKI_BASE=/home/...<redacted>.../MyData/fuseki/run
[2022-11-21 11:08:06.565] INFO [main] [org.apache.jena.fuseki.webapp.ShiroEnvironmentLoader.customizeEnvironment(ShiroEnvironmentLoader.java:80)] - Shiro file: file:///home/...<redacted>.../MyData/fuseki/run/shiro.ini
[2022-11-21 11:08:09.199] INFO [main] [org.apache.jena.fuseki.server.FusekiCoreInfo.lambda$infoPathsOperations$1(FusekiCoreInfo.java:90)] - Path = /MyData
[2022-11-21 11:08:09.204] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   Operation = query   Endpoints = [ "query", "sparql" ]
[2022-11-21 11:08:09.204] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   Operation = update  Endpoints = [ "update" ]
[2022-11-21 11:08:09.205] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   Operation = gsp-rw  Endpoints = [ "", "data" ]
[2022-11-21 11:08:09.206] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   Operation = gsp-r   Endpoints = [ "get" ]
[2022-11-21 11:08:09.206] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   Operation = upload  Endpoints = [ "upload" ]
[2022-11-21 11:08:09.208] INFO [main] [org.apache.jena.fuseki.server.PlatformInfo.logDetailsSystem(PlatformInfo.java:40)] - System
[2022-11-21 11:08:09.215] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   Memory: 4.0 GiB
[2022-11-21 11:08:09.216] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   Java:   11.0.17
[2022-11-21 11:08:09.217] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   OS:     Linux 5.15.0-53-generic amd64
[2022-11-21 11:08:09.217] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   PID:    69021
[2022-11-21 11:08:09.218] INFO [main] [org.apache.jena.fuseki.server.PlatformInfo.logDetailsJVM(PlatformInfo.java:65)] - Java
[2022-11-21 11:08:09.218] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   java.vendor          = Ubuntu
[2022-11-21 11:08:09.219] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   java.home            = /usr/lib/jvm/java-11-openjdk-amd64
[2022-11-21 11:08:09.220] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   java.runtime.version = 11.0.17+8-post-Ubuntu-1ubuntu222.04
[2022-11-21 11:08:09.220] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   java.runtime.name    = OpenJDK Runtime Environment
[2022-11-21 11:08:09.220] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   user.language        = en
[2022-11-21 11:08:09.221] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   user.timezone        = America/New_York
[2022-11-21 11:08:09.221] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   user.country         = US
[2022-11-21 11:08:09.221] INFO [main] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] -   user.dir             = /home/...<redacted>.../MyData/fuseki
[2022-11-21 11:08:09.299] INFO [main] [org.apache.jena.fuseki.cmd.JettyFusekiWebapp.start(JettyFusekiWebapp.java:130)] - Started 2022/11/21 11:08:09 EST on port 3030
[2022-11-21 11:08:50.898] INFO [qtp1984328477-41] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [1] GET http://localhost:3030/$/server
[2022-11-21 11:08:50.900] INFO [qtp1984328477-41] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [1]   => Accept:              application/json, text/plain, */*
[2022-11-21 11:08:50.901] INFO [qtp1984328477-41] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [1]   => Connection:          keep-alive
[2022-11-21 11:08:50.901] INFO [qtp1984328477-41] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [1]   => User-Agent:          Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/107.0.0.0 Safari/537.36
[2022-11-21 11:08:50.902] INFO [qtp1984328477-41] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [1]   => Referer:             http://localhost:3030/?
[2022-11-21 11:08:50.902] INFO [qtp1984328477-41] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [1]   => Sec-Fetch-Site:      same-origin
[2022-11-21 11:08:50.903] INFO [qtp1984328477-41] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [1]   => Sec-Fetch-Dest:      empty
[2022-11-21 11:08:50.903] INFO [qtp1984328477-41] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [1]   => Host:                localhost:3030
[2022-11-21 11:08:50.903] INFO [qtp1984328477-41] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [1]   => Accept-Encoding:     gzip, deflate, br
[2022-11-21 11:08:50.904] INFO [qtp1984328477-41] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [1]   => Sec-Fetch-Mode:      cors
[2022-11-21 11:08:50.905] INFO [qtp1984328477-41] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [1]   => sec-ch-ua:           "Google Chrome";v="107", "Chromium";v="107", "Not=A?Brand";v="24"
[2022-11-21 11:08:50.905] INFO [qtp1984328477-41] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [1]   => sec-ch-ua-mobile:    ?0
[2022-11-21 11:08:50.906] INFO [qtp1984328477-41] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [1]   => sec-ch-ua-platform:  "Linux"
[2022-11-21 11:08:50.906] INFO [qtp1984328477-41] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [1]   => Accept-Language:     en-US,en;q=0.9,ja;q=0.8
[2022-11-21 11:08:50.922] INFO [qtp1984328477-41] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [1]   <= Content-Type:        application/json
[2022-11-21 11:08:50.923] INFO [qtp1984328477-41] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [1]   <= Server:              Apache Jena Fuseki (4.7.0-SNAPSHOT)
[2022-11-21 11:08:50.924] INFO [qtp1984328477-41] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [1]   <= Vary:                Accept,Accept-Encoding,Accept-Charset,Origin,Access-Control-Request-Method,Access-Control-Request-Headers
[2022-11-21 11:08:50.924] INFO [qtp1984328477-41] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [1] 200 OK (15 ms)
[2022-11-21 11:09:05.926] INFO [qtp1984328477-43] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [2] GET http://localhost:3030/$/server
[2022-11-21 11:09:05.927] INFO [qtp1984328477-43] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [2]   => Accept:              application/json, text/plain, */*
[2022-11-21 11:09:05.927] INFO [qtp1984328477-43] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [2]   => Connection:          keep-alive
[2022-11-21 11:09:05.928] INFO [qtp1984328477-43] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [2]   => User-Agent:          Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/107.0.0.0 Safari/537.36
[2022-11-21 11:09:05.928] INFO [qtp1984328477-43] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [2]   => Referer:             http://localhost:3030/?
[2022-11-21 11:09:05.929] INFO [qtp1984328477-43] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [2]   => Sec-Fetch-Site:      same-origin
[2022-11-21 11:09:05.929] INFO [qtp1984328477-43] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [2]   => Sec-Fetch-Dest:      empty
[2022-11-21 11:09:05.929] INFO [qtp1984328477-43] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [2]   => Host:                localhost:3030
[2022-11-21 11:09:05.930] INFO [qtp1984328477-43] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [2]   => Accept-Encoding:     gzip, deflate, br
[2022-11-21 11:09:05.930] INFO [qtp1984328477-43] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [2]   => Sec-Fetch-Mode:      cors
[2022-11-21 11:09:05.931] INFO [qtp1984328477-43] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [2]   => sec-ch-ua:           "Google Chrome";v="107", "Chromium";v="107", "Not=A?Brand";v="24"
[2022-11-21 11:09:05.931] INFO [qtp1984328477-43] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [2]   => sec-ch-ua-mobile:    ?0
[2022-11-21 11:09:05.932] INFO [qtp1984328477-43] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [2]   => sec-ch-ua-platform:  "Linux"
[2022-11-21 11:09:05.932] INFO [qtp1984328477-43] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [2]   => Accept-Language:     en-US,en;q=0.9,ja;q=0.8
[2022-11-21 11:09:05.946] INFO [qtp1984328477-43] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [2]   <= Content-Type:        application/json
[2022-11-21 11:09:05.947] INFO [qtp1984328477-43] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [2]   <= Server:              Apache Jena Fuseki (4.7.0-SNAPSHOT)
[2022-11-21 11:09:05.948] INFO [qtp1984328477-43] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [2]   <= Vary:                Accept,Accept-Encoding,Accept-Charset,Origin,Access-Control-Request-Method,Access-Control-Request-Headers
[2022-11-21 11:09:05.948] INFO [qtp1984328477-43] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [2] 200 OK (13 ms)
[2022-11-21 11:09:06.401] INFO [qtp1984328477-45] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [3] GET http://localhost:3030/$/stats/MyData
[2022-11-21 11:09:06.401] INFO [qtp1984328477-45] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [3]   => Accept:              application/json, text/plain, */*
[2022-11-21 11:09:06.402] INFO [qtp1984328477-45] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [3]   => Connection:          keep-alive
[2022-11-21 11:09:06.403] INFO [qtp1984328477-45] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [3]   => User-Agent:          Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/107.0.0.0 Safari/537.36
[2022-11-21 11:09:06.403] INFO [qtp1984328477-45] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [3]   => Referer:             http://localhost:3030/?
[2022-11-21 11:09:06.403] INFO [qtp1984328477-45] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [3]   => Sec-Fetch-Site:      same-origin
[2022-11-21 11:09:06.404] INFO [qtp1984328477-45] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [3]   => Sec-Fetch-Dest:      empty
[2022-11-21 11:09:06.404] INFO [qtp1984328477-45] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [3]   => Host:                localhost:3030
[2022-11-21 11:09:06.405] INFO [qtp1984328477-45] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [3]   => Accept-Encoding:     gzip, deflate, br
[2022-11-21 11:09:06.405] INFO [qtp1984328477-45] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [3]   => Sec-Fetch-Mode:      cors
[2022-11-21 11:09:06.406] INFO [qtp1984328477-45] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [3]   => sec-ch-ua:           "Google Chrome";v="107", "Chromium";v="107", "Not=A?Brand";v="24"
[2022-11-21 11:09:06.406] INFO [qtp1984328477-45] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [3]   => sec-ch-ua-mobile:    ?0
[2022-11-21 11:09:06.407] INFO [qtp1984328477-45] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [3]   => sec-ch-ua-platform:  "Linux"
[2022-11-21 11:09:06.407] INFO [qtp1984328477-45] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [3]   => Accept-Language:     en-US,en;q=0.9,ja;q=0.8
[2022-11-21 11:09:06.407] INFO [qtp1984328477-45] [org.apache.jena.fuseki.ctl.ActionStats.execCommonItem(ActionStats.java:64)] - [3] GET stats dataset /MyData
[2022-11-21 11:09:06.433] INFO [qtp1984328477-45] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [3]   <= Content-Type:        application/json
[2022-11-21 11:09:06.434] INFO [qtp1984328477-45] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [3]   <= Content-Length:      1957
[2022-11-21 11:09:06.434] INFO [qtp1984328477-45] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [3]   <= Server:              Apache Jena Fuseki (4.7.0-SNAPSHOT)
[2022-11-21 11:09:06.435] INFO [qtp1984328477-45] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [3]   <= Vary:                Accept,Accept-Encoding,Accept-Charset,Origin,Access-Control-Request-Method,Access-Control-Request-Headers
[2022-11-21 11:09:06.435] INFO [qtp1984328477-45] [org.apache.jena.atlas.logging.FmtLog.info(FmtLog.java:85)] - [3] 200 OK (25 ms)

Error Log:

The following log captures the first test from the info -> count triples in all graphs through the reported error.

Nov 21 '22 17:11 AtesComp

The configuration is nearly the same as before.

Please could you attach the data and the config file to the issue so we are looking at exactly the same setup?

Nov 26 '22 14:11 afs

#1573 fixes some of the problems.

Please could you provide a minimal, complete config.ttl which causes problems when using 4.7.0 (when released) or 4.8.0-SNAPSHOT?

Dec 30 '22 10:12 afs

I'm re-looking at this as I have some time available. My tests still show the same failures with the released 4.7.0. I'm attempting to pair down my system to a condensed, shareable version. This is proving to be somewhat problematic.

I'll try an example data store with a minimal example in each. I'm hoping it is not related to the amount of data as it fails easily when a do the simple triple counts in the Fuseki dashboard and always on the second attempt. The counts are really not that big:

Apr 19 '23 02:04 AtesComp