Skip to content

500 error when editing #238

@FP234

Description

@FP234

Hello,

I've been starting to use MerMEId for Elgar works records, but have been encountering an error (three times in the past two days, on two different records). I also had the same error when using the Demo version a couple of months ago. I don't think there's been any patten in terms of when/why it happens (I was editing a text field the most recent time), but it does mean I lose recent changes as I can't press save.

The error is: "Submission response status: 500 Error type: resource-error URI: http://127.0.0.2:8080/apps/mermeid/data/elgar_inthesouth.xml"

David Lewis suggested this looks like a similar bug to #28 or #88? I've copied and pasted the log extract from around the time of the error (12:17:35) below.

Many thanks for any ideas! I'll keep an eye on if/when it happens again as I continue.
Frankie

2025-01-23 12:17:35,041 [qtp881977454-33] INFO (LifecycleLogger.scala [event]:102) - event: {"request": "190", "session": "node0180624o6fabsq1646dplpk7ezw2", "source": "limiter", "message": "start: filter", "path": "/xforms-server", "method": "POST"}
2025-01-23 12:17:35,044 [qtp881977454-33] INFO (LifecycleLogger.scala [event]:102) - event: {"request": "190", "session": "node0180624o6fabsq1646dplpk7ezw2", "source": "limiter", "message": "start: chain", "path": "/xforms-server", "method": "POST", "wait": "1"}
2025-01-23 12:17:35,045 [qtp881977454-33] DEBUG (FormRunnerAuth.scala [findCredentialsFromContainerOrHeaders]:134) - using Container method
2025-01-23 12:17:35,046 [qtp881977454-33] DEBUG (FormRunnerAuth.scala [findCredentialsFromContainerOrHeaders]:139) - usernameOpt: None, roles: None
2025-01-23 12:17:35,046 [qtp881977454-33] WARN (FormRunnerAuth.scala [getCredentialsAsHeadersUseSession]:86) - not setting credentials headers because credentials are not found
2025-01-23 12:17:35,048 [qtp881977454-33] INFO (LifecycleLogger.scala [event]:102) - event: {"request": "190", "session": "node0180624o6fabsq1646dplpk7ezw2", "source": "service", "message": "start: handle"}
2025-01-23 12:17:35,081 [qtp881977454-33] INFO (LifecycleLogger.scala [event]:102) - event: {"request": "190", "session": "node0180624o6fabsq1646dplpk7ezw2", "source": "xforms", "message": "ajax with update events", "uuid": "828fe0b34fbc510f2a713564bae2c360cce74efc"}
2025-01-23 12:17:35,081 [qtp881977454-33] INFO (LifecycleLogger.scala [event]:102) - event: {"request": "190", "session": "node0180624o6fabsq1646dplpk7ezw2", "source": "xforms", "message": "before document lock", "uuid": "828fe0b34fbc510f2a713564bae2c360cce74efc"}
2025-01-23 12:17:35,081 [qtp881977454-33] INFO (LifecycleLogger.scala [event]:102) - event: {"request": "190", "session": "node0180624o6fabsq1646dplpk7ezw2", "source": "xforms", "message": "got document lock", "path": "/xforms-server", "method": "POST", "uuid": "828fe0b34fbc510f2a713564bae2c360cce74efc", "wait": "0"}
2025-01-23 12:17:35,136 [qtp881977454-502] INFO (Transform.java [extractSerializationProperties]:414) - Setting serialization property: method = xml
2025-01-23 12:17:35,136 [qtp881977454-502] INFO (Transform.java [extractSerializationProperties]:414) - Setting serialization property: media-type = application/xml
2025-01-23 12:17:35,189 [qtp881977454-502] WARN (TransactionManager.java [close]:421) - Transaction was not committed or aborted, auto aborting!
2025-01-23 12:17:35,190 [qtp881977454-502] ERROR (XMLDBStore.java [evalWithCollection]:230) - A resource with the same name already exists in the target collection '/db/apps/mermeid-data', and you do not have write access on that resource.
org.xmldb.api.base.XMLDBException: A resource with the same name already exists in the target collection '/db/apps/mermeid-data', and you do not have write access on that resource.
      at org.exist.xmldb.function.LocalXmldbCollectionFunction.apply(LocalXmldbCollectionFunction.java:52) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xmldb.function.LocalXmldbCollectionFunction.apply(LocalXmldbCollectionFunction.java:50) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xmldb.AbstractLocal.lambda$6(AbstractLocal.java:218) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xmldb.AbstractLocal.lambda$5(AbstractLocal.java:152) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xmldb.function.LocalXmldbFunction.apply(LocalXmldbFunction.java:48) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xmldb.txn.bridge.InTxnLocalCollection.withDb(InTxnLocalCollection.java:59) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xmldb.txn.bridge.InTxnLocalCollection.withDb(InTxnLocalCollection.java:53) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xmldb.AbstractLocal.lambda$4(AbstractLocal.java:152) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xmldb.LocalCollection.lambda$29(LocalCollection.java:823) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xmldb.LocalCollection.storeXMLResource(LocalCollection.java:622) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xmldb.LocalCollection.storeResource(LocalCollection.java:572) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xmldb.LocalCollection.storeResource(LocalCollection.java:561) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.functions.xmldb.XMLDBStore.evalWithCollection(XMLDBStore.java:226) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.functions.xmldb.XMLDBAbstractCollectionManipulator.eval(XMLDBAbstractCollectionManipulator.java:169) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.BasicFunction.eval(BasicFunction.java:73) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.InternalFunctionCall.eval(InternalFunctionCall.java:62) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.LetExpr.eval(LetExpr.java:98) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.LetExpr.eval(LetExpr.java:110) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.LetExpr.eval(LetExpr.java:110) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.PathExpr.eval(PathExpr.java:280) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.TryCatchExpression.eval(TryCatchExpression.java:148) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.PathExpr.eval(PathExpr.java:280) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.SwitchExpression.eval(SwitchExpression.java:109) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.PathExpr.eval(PathExpr.java:280) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.SequenceConstructor.eval(SequenceConstructor.java:82) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.ConditionalExpression.eval(ConditionalExpression.java:98) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.PathExpr.eval(PathExpr.java:280) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.SequenceConstructor.eval(SequenceConstructor.java:82) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.PathExpr.eval(PathExpr.java:280) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.PathExpr.eval(PathExpr.java:280) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.XQuery.execute(XQuery.java:445) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.XQuery.execute(XQuery.java:348) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xquery.XQuery.execute(XQuery.java:335) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.http.urlrewrite.XQueryURLRewrite.runQuery(XQueryURLRewrite.java:677) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.http.urlrewrite.XQueryURLRewrite.service(XQueryURLRewrite.java:239) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at javax.servlet.http.HttpServlet.service(HttpServlet.java:790) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.servlet.ServletHolder$NotAsync.service(ServletHolder.java:1450) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1656) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.websocket.server.WebSocketUpgradeFilter.doFilter(WebSocketUpgradeFilter.java:292) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1626) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:552) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:571) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1440) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:505) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1355) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:234) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:146) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:722) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.Server.handle(Server.java:516) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:487) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:732) [exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:479) [exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277) [exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) [exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) [exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) [exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) [exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) [exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409) [exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) [exist.uber.jar:6.3.0-SNAPSHOT]
      at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) [exist.uber.jar:6.3.0-SNAPSHOT]
      at java.lang.Thread.run(Thread.java:750) [?:1.8.0_432]
Caused by: org.exist.security.PermissionDeniedException: A resource with the same name already exists in the target collection '/db/apps/mermeid-data', and you do not have write access on that resource.
      at org.exist.collections.MutableCollection.checkPermissionsForAddDocument(MutableCollection.java:1707) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.collections.MutableCollection.validateXMLResourceInternal(MutableCollection.java:1542) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.collections.MutableCollection.storeXmlDocument(MutableCollection.java:1207) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.collections.MutableCollection.storeDocument(MutableCollection.java:1148) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.collections.LockedCollection.storeDocument(LockedCollection.java:367) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.storage.NativeBroker.storeDocument(NativeBroker.java:2154) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xmldb.LocalCollection.lambda$19(LocalCollection.java:657) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      at org.exist.xmldb.function.LocalXmldbCollectionFunction.apply(LocalXmldbCollectionFunction.java:50) ~[exist.uber.jar:6.3.0-SNAPSHOT]
      ... 75 more
2025-01-23 12:17:35,235 [qtp881977454-33] INFO (LifecycleLogger.scala [event]:102) - event: {"request": "190", "session": "node0180624o6fabsq1646dplpk7ezw2", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "1", "document cache max size": "50"}
2025-01-23 12:17:35,235 [qtp881977454-33] INFO (LifecycleLogger.scala [event]:102) - event: {"request": "190", "session": "node0180624o6fabsq1646dplpk7ezw2", "source": "service", "message": "end: handle", "time": "187 ms"}
2025-01-23 12:17:35,235 [qtp881977454-33] INFO (LifecycleLogger.scala [event]:102) - event: {"request": "190", "session": "node0180624o6fabsq1646dplpk7ezw2", "source": "limiter", "message": "end: chain", "time": "192 ms"}
2025-01-23 12:17:35,235 [qtp881977454-33] INFO (LifecycleLogger.scala [event]:102) - event: {"request": "190", "session": "node0180624o6fabsq1646dplpk7ezw2", "source": "limiter", "message": "end: filter", "time": "199 ms"}

Metadata

Metadata

Assignees

No one assigned

    Labels

    No labels
    No labels

    Type

    No type

    Projects

    No projects

    Milestone

    No milestone

    Relationships

    None yet

    Development

    No branches or pull requests

    Issue actions