Uploaded image for project: 'Publishing'
  1. Publishing
  2. PUBLISHING-88

Keep track of lock owners with session data

    XMLWordPrintable

    Details

    • Type: Improvement
    • Status: Closed
    • Priority: Neutral
    • Resolution: Done
    • Affects Version/s: 1.1.5, 1.2.1
    • Fix Version/s: 1.1.6, 1.2.2
    • Labels:
    • Sprint:
      Maintenance 22
    • Story Points:
      1
    • Magnolia Release:
      5.7.8, 6.2.3

      Description

      When we lock an item for publication we should put some data into ownerInfo field so we can better track the locks.

      Example using /travel/strories/story.

      Successful publish:

      2020-08-29 09:07:05,380 DEBUG ional.receiver.locking.TransactionalJcrLockManager: About to begin publish of website:a096ec20-c190-4b2e-91a4-294c99b64049:/travel/stories/story
      2020-08-29 09:07:05,381 DEBUG ional.receiver.locking.TransactionalJcrLockManager: 373:1598684825376 Requesting XA lock
      2020-08-29 09:07:05,384 DEBUG info.magnolia.publishing.locking.JcrLockManager   : parent path:/travel/stories
      2020-08-29 09:07:05,421 DEBUG info.magnolia.publishing.locking.JcrLockManager   : session-admin-267 DID locked website:/travel/stories
      2020-08-29 09:07:05,423 DEBUG ional.receiver.locking.TransactionalJcrLockManager: 373:1598684825376 Got clearance for JCR lock on parent node /travel/stories
      2020-08-29 09:07:05,423 DEBUG receiver.operation.jcr.AbstractJcrReceiveOperation: Checking the timestamp of publication request
      2020-08-29 09:07:05,423 DEBUG r.operation.jcr.JcrTransactionPublicationOperation: 373:1598684825376 Starting content publication
      2020-08-29 09:07:05,423 DEBUG r.operation.jcr.JcrTransactionPublicationOperation: 373:1598684825376 Checking for existence of content website:a096ec20-c190-4b2e-91a4-294c99b64049
      2020-08-29 09:07:05,423 DEBUG r.operation.jcr.JcrTransactionPublicationOperation: 373:1598684825376 Content a096ec20-c190-4b2e-91a4-294c99b64049 was located under path website:/travel/stories/story
      2020-08-29 09:07:05,513 DEBUG r.operation.jcr.JcrTransactionPublicationOperation: Stored content website:/travel/stories/story:a096ec20-c190-4b2e-91a4-294c99b64049 for rollback later
      2020-08-29 09:07:05,560 INFO  r.operation.jcr.JcrTransactionPublicationOperation: 373:1598684825376 User superuser successfully published website:/travel/stories/story on dx-core-webapp.
      2020-08-29 09:07:05,560 DEBUG receiver.operation.jcr.AbstractJcrReceiveOperation: Ordering story before main
      2020-08-29 09:07:05,590 DEBUG info.magnolia.publishing.locking.JcrLockManager   : session-admin-267 DID unlocked website:/travel/stories
      2020-08-29 09:07:05,603 DEBUG ional.receiver.locking.TransactionalJcrLockManager: About to begin commit of website:a096ec20-c190-4b2e-91a4-294c99b64049:/travel/stories/story
      2020-08-29 09:07:05,603 DEBUG info.magnolia.publishing.locking.JcrLockManager   : node uuid:a096ec20-c190-4b2e-91a4-294c99b64049
      2020-08-29 09:07:05,620 DEBUG info.magnolia.publishing.locking.JcrLockManager   : session-admin-269 DID locked website:/travel/stories/story
      2020-08-29 09:07:05,620 DEBUG ional.receiver.locking.TransactionalJcrLockManager: 370 Got clearance for JCR lock on parent node /travel/stories/story
      2020-08-29 09:07:05,620 DEBUG actional.receiver.operation.jcr.JcrCommitOperation: 370 Proceeding with commit of content a096ec20-c190-4b2e-91a4-294c99b64049
      2020-08-29 09:07:05,637 DEBUG actional.receiver.operation.jcr.JcrCommitOperation: 370 Commited node a096ec20-c190-4b2e-91a4-294c99b64049
      2020-08-29 09:07:05,647 DEBUG info.magnolia.publishing.locking.JcrLockManager   : session-admin-269 DID unlocked website:/travel/stories/story
      

      Lock node on public using a groovy script. Provide ctx info about who locked the node.

      import javax.jcr.Session
      import info.magnolia.context.MgnlContext
       
      session = MgnlContext.getJCRSession("website");
      lockManager = session.getWorkspace().getLockManager();
      lockManager.lock("/travel/stories", true, true, 15000, "groovy-lock-" + session);
      session.save();
      
      println lockManager.isLocked("/travel/stories")
      

      Unsuccessful publish:

      2020-08-31 15:01:28,198 DEBUG ional.receiver.locking.TransactionalJcrLockManager: 340 About to begin publish of website:a096ec20-c190-4b2e-91a4-294c99b64049:/travel/stories/story:session-admin-317
      2020-08-31 15:01:28,198 DEBUG ional.receiver.locking.TransactionalJcrLockManager: 340 Requesting XA lock for node:node /travel/stories/story, session:session-admin-317
      2020-08-31 15:01:28,202 DEBUG info.magnolia.publishing.locking.JcrLockManager   : parent path:/travel/stories
      2020-08-31 15:01:28,203 INFO  info.magnolia.publishing.locking.JcrLockManager   : Node /travel/stories is locked. Will retry 10 more times.
      2020-08-31 15:01:30,206 DEBUG info.magnolia.publishing.locking.JcrLockManager   : parent path:/travel/stories
      2020-08-31 15:01:30,206 INFO  info.magnolia.publishing.locking.JcrLockManager   : Node /travel/stories is locked. Will retry 9 more times.
      2020-08-31 15:01:32,210 DEBUG info.magnolia.publishing.locking.JcrLockManager   : parent path:/travel/stories
      2020-08-31 15:01:32,210 INFO  info.magnolia.publishing.locking.JcrLockManager   : Node /travel/stories is locked. Will retry 8 more times.
      2020-08-31 15:01:34,210 DEBUG info.magnolia.publishing.locking.JcrLockManager   : parent path:/travel/stories
      2020-08-31 15:01:34,211 INFO  info.magnolia.publishing.locking.JcrLockManager   : Node /travel/stories is locked. Will retry 7 more times.
      2020-08-31 15:01:36,216 DEBUG info.magnolia.publishing.locking.JcrLockManager   : parent path:/travel/stories
      2020-08-31 15:01:36,216 INFO  info.magnolia.publishing.locking.JcrLockManager   : Node /travel/stories is locked. Will retry 6 more times.
      2020-08-31 15:01:38,221 DEBUG info.magnolia.publishing.locking.JcrLockManager   : parent path:/travel/stories
      2020-08-31 15:01:38,221 INFO  info.magnolia.publishing.locking.JcrLockManager   : Node /travel/stories is locked. Will retry 5 more times.
      2020-08-31 15:01:40,222 DEBUG info.magnolia.publishing.locking.JcrLockManager   : parent path:/travel/stories
      2020-08-31 15:01:40,222 INFO  info.magnolia.publishing.locking.JcrLockManager   : Node /travel/stories is locked. Will retry 4 more times.
      2020-08-31 15:01:42,227 DEBUG info.magnolia.publishing.locking.JcrLockManager   : parent path:/travel/stories
      2020-08-31 15:01:42,227 INFO  info.magnolia.publishing.locking.JcrLockManager   : Node /travel/stories is locked. Will retry 3 more times.
      2020-08-31 15:01:44,232 DEBUG info.magnolia.publishing.locking.JcrLockManager   : parent path:/travel/stories
      2020-08-31 15:01:44,232 INFO  info.magnolia.publishing.locking.JcrLockManager   : Node /travel/stories is locked. Will retry 2 more times.
      2020-08-31 15:01:46,236 DEBUG info.magnolia.publishing.locking.JcrLockManager   : parent path:/travel/stories
      2020-08-31 15:01:46,237 INFO  info.magnolia.publishing.locking.JcrLockManager   : Node /travel/stories is locked. Will retry 1 more times.
      2020-08-31 15:01:48,242 ERROR info.magnolia.publishing.locking.JcrLockManager   : Cannot acquire lock [owner: groovy-session-session-admin-293 expires: 241.17 mins]
      2020-08-31 15:01:48,243 DEBUG ional.receiver.locking.TransactionalJcrLockManager: 340 Cannot lock node node /travel/stories/story, another transaction using the node is still progress
      2020-08-31 15:01:48,243 INFO  ional.receiver.locking.TransactionalJcrLockManager: 340 Node /travel/stories/story is locked by transaction. Will retry 10 more times.
      2020-08-31 15:01:50,248 ERROR ional.receiver.locking.TransactionalJcrLockManager: Operation not permitted, /travel/stories is locked
      2020-08-31 15:01:50,248 DEBUG receiver.operation.jcr.AbstractJcrReceiveOperation: Checking the timestamp of publication request
      2020-08-31 15:01:50,248 DEBUG r.operation.jcr.JcrTransactionPublicationOperation: 340 Starting content publication
      2020-08-31 15:01:50,248 DEBUG r.operation.jcr.JcrTransactionPublicationOperation: 340 Checking for existence of content website:a096ec20-c190-4b2e-91a4-294c99b64049
      2020-08-31 15:01:50,248 DEBUG r.operation.jcr.JcrTransactionPublicationOperation: 340 Content a096ec20-c190-4b2e-91a4-294c99b64049 was located under path website:/travel/stories/story
      2020-08-31 15:01:50,339 DEBUG r.operation.jcr.JcrTransactionPublicationOperation: Stored content website:/travel/stories/story:a096ec20-c190-4b2e-91a4-294c99b64049 for rollback later
      2020-08-31 15:01:50,393 INFO  r.operation.jcr.JcrTransactionPublicationOperation: 340 User superuser successfully published website:/travel/stories/story on dx-core-webapp.
      2020-08-31 15:01:50,394 DEBUG receiver.operation.jcr.AbstractJcrReceiveOperation: Ordering story before main
      2020-08-31 15:01:50,395 DEBUG receiver.operation.jcr.AbstractJcrReceiveOperation: Failed to order node
      javax.jcr.lock.LockException: Node locked.
      	at org.apache.jackrabbit.core.lock.LockManagerImpl.checkLock(LockManagerImpl.java:694) ~[jackrabbit-core-2.20.0.jar:2.20.0]
      	at org.apache.jackrabbit.core.lock.LockManagerImpl.checkLock(LockManagerImpl.java:670) ~[jackrabbit-core-2.20.0.jar:2.20.0]
      	at org.apache.jackrabbit.core.lock.LockManagerImpl.checkLock(LockManagerImpl.java:655) ~[jackrabbit-core-2.20.0.jar:2.20.0]
      	at org.apache.jackrabbit.core.lock.XALockManager.checkLock(XALockManager.java:174) ~[jackrabbit-core-2.20.0.jar:2.20.0]
      	at org.apache.jackrabbit.core.ItemValidator.checkLock(ItemValidator.java:367) ~[jackrabbit-core-2.20.0.jar:2.20.0]
      	at org.apache.jackrabbit.core.ItemValidator.checkCondition(ItemValidator.java:288) ~[jackrabbit-core-2.20.0.jar:2.20.0]
      	at org.apache.jackrabbit.core.ItemValidator.checkModify(ItemValidator.java:248) ~[jackrabbit-core-2.20.0.jar:2.20.0]
      	at org.apache.jackrabbit.core.NodeImpl.orderBefore(NodeImpl.java:1470) ~[jackrabbit-core-2.20.0.jar:2.20.0]
      	at org.apache.jackrabbit.core.NodeImpl.orderBefore(NodeImpl.java:1861) ~[jackrabbit-core-2.20.0.jar:2.20.0]
      	at info.magnolia.jcr.wrapper.DelegateNodeWrapper.orderBefore(DelegateNodeWrapper.java:339) ~[magnolia-core-6.2.2.jar:?]
      	at info.magnolia.jcr.wrapper.DelegateNodeWrapper.orderBefore(DelegateNodeWrapper.java:339) ~[magnolia-core-6.2.2.jar:?]
      	at info.magnolia.jcr.wrapper.DelegateNodeWrapper.orderBefore(DelegateNodeWrapper.java:339) ~[magnolia-core-6.2.2.jar:?]
      	at info.magnolia.jcr.wrapper.MgnlPropertySettingNodeWrapper.orderBefore(MgnlPropertySettingNodeWrapper.java:222) ~[magnolia-core-6.2.2.jar:?]
      	at info.magnolia.jcr.wrapper.DelegateNodeWrapper.orderBefore(DelegateNodeWrapper.java:339) ~[magnolia-core-6.2.2.jar:?]
      	at info.magnolia.jcr.wrapper.DelegateNodeWrapper.orderBefore(DelegateNodeWrapper.java:339) ~[magnolia-core-6.2.2.jar:?]
      	at info.magnolia.publishing.receiver.operation.jcr.AbstractJcrReceiveOperation.orderChildNodes(AbstractJcrReceiveOperation.java:390) ~[magnolia-publishing-receiver-1.2.1.jar:?]
      	at info.magnolia.publishing.receiver.operation.jcr.AbstractJcrReceiveOperation.orderImportedNode(AbstractJcrReceiveOperation.java:312) [magnolia-publishing-receiver-1.2.1.jar:?]
      	at info.magnolia.publishing.receiver.operation.jcr.AbstractJcrReceiveOperation.postExecute(AbstractJcrReceiveOperation.java:182) [magnolia-publishing-receiver-1.2.1.jar:?]
      	at info.magnolia.publishing.receiver.operation.jcr.AbstractJcrReceiveOperation.execute(AbstractJcrReceiveOperation.java:148) [magnolia-publishing-receiver-1.2.1.jar:?]
      	at info.magnolia.publishing.transactional.receiver.operation.jcr.JcrTransactionPublicationOperation.execute(JcrTransactionPublicationOperation.java:87) [magnolia-publishing-transactional-receiver-1.0.7-SNAPSHOT.jar:?]
      	at info.magnolia.publishing.dispatcher.AbstractDispatcher.dispatch(AbstractDispatcher.java:105) [magnolia-publishing-core-1.2.2-SNAPSHOT.jar:?]
      	at info.magnolia.publishing.transactional.receiver.dispatcher.TransactionalHeadersDispatcher.dispatch(TransactionalHeadersDispatcher.java:57) [magnolia-publishing-transactional-receiver-1.0.7-SNAPSHOT.jar:?]
      	at info.magnolia.publishing.transactional.receiver.dispatcher.TransactionalHeadersDispatcher.dispatch(TransactionalHeadersDispatcher.java:1) [magnolia-publishing-transactional-receiver-1.0.7-SNAPSHOT.jar:?]
      	at info.magnolia.publishing.receiver.filter.PublicationFilter.doFilter(PublicationFilter.java:61) [magnolia-publishing-receiver-1.2.1.jar:?]
      	at info.magnolia.cms.filters.AbstractMgnlFilter.doFilter(AbstractMgnlFilter.java:85) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:79) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.security.auth.login.LoginFilter.doFilter(LoginFilter.java:127) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.AbstractMgnlFilter.doFilter(AbstractMgnlFilter.java:85) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:79) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.enterprise.registration.RegistrationFilter.doFilter(RegistrationFilter.java:79) [magnolia-module-enterprise-6.2.2.jar:?]
      	at info.magnolia.cms.filters.AbstractMgnlFilter.doFilter(AbstractMgnlFilter.java:85) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:79) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:81) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:81) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MultipartRequestFilter.doFilter(MultipartRequestFilter.java:151) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.OncePerRequestAbstractMgnlFilter.doFilter(OncePerRequestAbstractMgnlFilter.java:59) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:79) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.personalization.preview.filter.PreviewFilter.doFilter(PreviewFilter.java:92) [magnolia-personalization-preview-app-2.0.2.jar:?]
      	at info.magnolia.cms.filters.OncePerRequestAbstractMgnlFilter.doFilter(OncePerRequestAbstractMgnlFilter.java:59) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:79) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:81) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:81) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:81) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:81) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.ContentTypeFilter.doFilter(ContentTypeFilter.java:155) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.AbstractMgnlFilter.doFilter(AbstractMgnlFilter.java:85) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:79) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:81) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.ContextFilter.doFilter(ContextFilter.java:128) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.AbstractMgnlFilter.doFilter(AbstractMgnlFilter.java:85) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:79) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.CompositeFilter.doFilter(CompositeFilter.java:65) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.AbstractMgnlFilter.doFilter(AbstractMgnlFilter.java:85) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.SafeDestroyMgnlFilterWrapper.doFilter(SafeDestroyMgnlFilterWrapper.java:107) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterDispatcher.doDispatch(MgnlFilterDispatcher.java:67) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlMainFilter.doFilter(MgnlMainFilter.java:110) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlMainFilter.doFilter(MgnlMainFilter.java:96) [magnolia-core-6.2.2.jar:?]
      	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) [catalina.jar:9.0.37]
      	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) [catalina.jar:9.0.37]
      	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:202) [catalina.jar:9.0.37]
      	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:96) [catalina.jar:9.0.37]
      	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:541) [catalina.jar:9.0.37]
      	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:139) [catalina.jar:9.0.37]
      	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:92) [catalina.jar:9.0.37]
      	at org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:690) [catalina.jar:9.0.37]
      	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:74) [catalina.jar:9.0.37]
      	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:343) [catalina.jar:9.0.37]
      	at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:373) [tomcat-coyote.jar:9.0.37]
      	at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:65) [tomcat-coyote.jar:9.0.37]
      	at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:868) [tomcat-coyote.jar:9.0.37]
      	at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1589) [tomcat-coyote.jar:9.0.37]
      	at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49) [tomcat-coyote.jar:9.0.37]
      	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_261]
      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_261]
      	at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) [tomcat-util.jar:9.0.37]
      	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_261]
      2020-08-31 15:01:50,411 DEBUG receiver.operation.jcr.AbstractJcrReceiveOperation: Ordering story before footer
      2020-08-31 15:01:50,411 DEBUG receiver.operation.jcr.AbstractJcrReceiveOperation: Failed to order node
      javax.jcr.lock.LockException: Node locked.
      	at org.apache.jackrabbit.core.lock.LockManagerImpl.checkLock(LockManagerImpl.java:694) ~[jackrabbit-core-2.20.0.jar:2.20.0]
      	at org.apache.jackrabbit.core.lock.LockManagerImpl.checkLock(LockManagerImpl.java:670) ~[jackrabbit-core-2.20.0.jar:2.20.0]
      	at org.apache.jackrabbit.core.lock.LockManagerImpl.checkLock(LockManagerImpl.java:655) ~[jackrabbit-core-2.20.0.jar:2.20.0]
      	at org.apache.jackrabbit.core.lock.XALockManager.checkLock(XALockManager.java:174) ~[jackrabbit-core-2.20.0.jar:2.20.0]
      	at org.apache.jackrabbit.core.ItemValidator.checkLock(ItemValidator.java:367) ~[jackrabbit-core-2.20.0.jar:2.20.0]
      	at org.apache.jackrabbit.core.ItemValidator.checkCondition(ItemValidator.java:288) ~[jackrabbit-core-2.20.0.jar:2.20.0]
      	at org.apache.jackrabbit.core.ItemValidator.checkModify(ItemValidator.java:248) ~[jackrabbit-core-2.20.0.jar:2.20.0]
      	at org.apache.jackrabbit.core.NodeImpl.orderBefore(NodeImpl.java:1470) ~[jackrabbit-core-2.20.0.jar:2.20.0]
      	at org.apache.jackrabbit.core.NodeImpl.orderBefore(NodeImpl.java:1861) ~[jackrabbit-core-2.20.0.jar:2.20.0]
      	at info.magnolia.jcr.wrapper.DelegateNodeWrapper.orderBefore(DelegateNodeWrapper.java:339) ~[magnolia-core-6.2.2.jar:?]
      	at info.magnolia.jcr.wrapper.DelegateNodeWrapper.orderBefore(DelegateNodeWrapper.java:339) ~[magnolia-core-6.2.2.jar:?]
      	at info.magnolia.jcr.wrapper.DelegateNodeWrapper.orderBefore(DelegateNodeWrapper.java:339) ~[magnolia-core-6.2.2.jar:?]
      	at info.magnolia.jcr.wrapper.MgnlPropertySettingNodeWrapper.orderBefore(MgnlPropertySettingNodeWrapper.java:222) ~[magnolia-core-6.2.2.jar:?]
      	at info.magnolia.jcr.wrapper.DelegateNodeWrapper.orderBefore(DelegateNodeWrapper.java:339) ~[magnolia-core-6.2.2.jar:?]
      	at info.magnolia.jcr.wrapper.DelegateNodeWrapper.orderBefore(DelegateNodeWrapper.java:339) ~[magnolia-core-6.2.2.jar:?]
      	at info.magnolia.publishing.receiver.operation.jcr.AbstractJcrReceiveOperation.orderChildNodes(AbstractJcrReceiveOperation.java:390) ~[magnolia-publishing-receiver-1.2.1.jar:?]
      	at info.magnolia.publishing.receiver.operation.jcr.AbstractJcrReceiveOperation.orderImportedNode(AbstractJcrReceiveOperation.java:312) [magnolia-publishing-receiver-1.2.1.jar:?]
      	at info.magnolia.publishing.receiver.operation.jcr.AbstractJcrReceiveOperation.postExecute(AbstractJcrReceiveOperation.java:182) [magnolia-publishing-receiver-1.2.1.jar:?]
      	at info.magnolia.publishing.receiver.operation.jcr.AbstractJcrReceiveOperation.execute(AbstractJcrReceiveOperation.java:148) [magnolia-publishing-receiver-1.2.1.jar:?]
      	at info.magnolia.publishing.transactional.receiver.operation.jcr.JcrTransactionPublicationOperation.execute(JcrTransactionPublicationOperation.java:87) [magnolia-publishing-transactional-receiver-1.0.7-SNAPSHOT.jar:?]
      	at info.magnolia.publishing.dispatcher.AbstractDispatcher.dispatch(AbstractDispatcher.java:105) [magnolia-publishing-core-1.2.2-SNAPSHOT.jar:?]
      	at info.magnolia.publishing.transactional.receiver.dispatcher.TransactionalHeadersDispatcher.dispatch(TransactionalHeadersDispatcher.java:57) [magnolia-publishing-transactional-receiver-1.0.7-SNAPSHOT.jar:?]
      	at info.magnolia.publishing.transactional.receiver.dispatcher.TransactionalHeadersDispatcher.dispatch(TransactionalHeadersDispatcher.java:1) [magnolia-publishing-transactional-receiver-1.0.7-SNAPSHOT.jar:?]
      	at info.magnolia.publishing.receiver.filter.PublicationFilter.doFilter(PublicationFilter.java:61) [magnolia-publishing-receiver-1.2.1.jar:?]
      	at info.magnolia.cms.filters.AbstractMgnlFilter.doFilter(AbstractMgnlFilter.java:85) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:79) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.security.auth.login.LoginFilter.doFilter(LoginFilter.java:127) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.AbstractMgnlFilter.doFilter(AbstractMgnlFilter.java:85) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:79) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.enterprise.registration.RegistrationFilter.doFilter(RegistrationFilter.java:79) [magnolia-module-enterprise-6.2.2.jar:?]
      	at info.magnolia.cms.filters.AbstractMgnlFilter.doFilter(AbstractMgnlFilter.java:85) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:79) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:81) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:81) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MultipartRequestFilter.doFilter(MultipartRequestFilter.java:151) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.OncePerRequestAbstractMgnlFilter.doFilter(OncePerRequestAbstractMgnlFilter.java:59) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:79) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.personalization.preview.filter.PreviewFilter.doFilter(PreviewFilter.java:92) [magnolia-personalization-preview-app-2.0.2.jar:?]
      	at info.magnolia.cms.filters.OncePerRequestAbstractMgnlFilter.doFilter(OncePerRequestAbstractMgnlFilter.java:59) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:79) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:81) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:81) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:81) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:81) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.ContentTypeFilter.doFilter(ContentTypeFilter.java:155) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.AbstractMgnlFilter.doFilter(AbstractMgnlFilter.java:85) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:79) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:81) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.ContextFilter.doFilter(ContextFilter.java:128) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.AbstractMgnlFilter.doFilter(AbstractMgnlFilter.java:85) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterChain.doFilter(MgnlFilterChain.java:79) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.CompositeFilter.doFilter(CompositeFilter.java:65) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.AbstractMgnlFilter.doFilter(AbstractMgnlFilter.java:85) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.SafeDestroyMgnlFilterWrapper.doFilter(SafeDestroyMgnlFilterWrapper.java:107) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlFilterDispatcher.doDispatch(MgnlFilterDispatcher.java:67) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlMainFilter.doFilter(MgnlMainFilter.java:110) [magnolia-core-6.2.2.jar:?]
      	at info.magnolia.cms.filters.MgnlMainFilter.doFilter(MgnlMainFilter.java:96) [magnolia-core-6.2.2.jar:?]
      	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) [catalina.jar:9.0.37]
      	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) [catalina.jar:9.0.37]
      	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:202) [catalina.jar:9.0.37]
      	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:96) [catalina.jar:9.0.37]
      	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:541) [catalina.jar:9.0.37]
      	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:139) [catalina.jar:9.0.37]
      	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:92) [catalina.jar:9.0.37]
      	at org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:690) [catalina.jar:9.0.37]
      	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:74) [catalina.jar:9.0.37]
      	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:343) [catalina.jar:9.0.37]
      	at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:373) [tomcat-coyote.jar:9.0.37]
      	at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:65) [tomcat-coyote.jar:9.0.37]
      	at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:868) [tomcat-coyote.jar:9.0.37]
      	at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1589) [tomcat-coyote.jar:9.0.37]
      	at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49) [tomcat-coyote.jar:9.0.37]
      	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_261]
      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_261]
      	at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) [tomcat-util.jar:9.0.37]
      	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_261]
      2020-08-31 15:01:50,413 WARN  info.magnolia.publishing.locking.JcrLockManager   : Node not locked by session: f8cbedd6-df91-4d7c-a952-2dc414d97704
      2020-08-31 15:01:50,456 DEBUG ional.receiver.locking.TransactionalJcrLockManager: 347 About to begin commit of website:a096ec20-c190-4b2e-91a4-294c99b64049:/travel/stories/story:session-admin-319
      2020-08-31 15:01:50,456 DEBUG info.magnolia.publishing.locking.JcrLockManager   : node uuid:a096ec20-c190-4b2e-91a4-294c99b64049
      2020-08-31 15:01:50,477 DEBUG info.magnolia.publishing.locking.JcrLockManager   : session-admin-319 DID locked website:/travel/stories/story
      2020-08-31 15:01:50,477 DEBUG ional.receiver.locking.TransactionalJcrLockManager: 347 Got clearance for JCR lock on parent node /travel/stories/story
      2020-08-31 15:01:50,477 DEBUG actional.receiver.operation.jcr.JcrCommitOperation: 347 Proceeding with commit of content a096ec20-c190-4b2e-91a4-294c99b64049
      2020-08-31 15:01:50,477 DEBUG actional.receiver.operation.jcr.JcrCommitOperation: 347 session:session-admin-320
      2020-08-31 15:01:50,478 DEBUG actional.receiver.operation.jcr.JcrCommitOperation: 347 Removing node:node /story
      2020-08-31 15:01:50,494 DEBUG actional.receiver.operation.jcr.JcrCommitOperation: 347 Committed node a096ec20-c190-4b2e-91a4-294c99b64049
      2020-08-31 15:01:50,507 DEBUG info.magnolia.publishing.locking.JcrLockManager   : session-admin-319 DID unlocked website:/travel/stories/story
      

      Now we can see the node was locked by a groovy session:

      2020-08-31 15:01:48,242 ERROR info.magnolia.publishing.locking.JcrLockManager   : Cannot acquire lock [owner: groovy-session-session-admin-293 expires: 241.17 mins]
      

        Attachments

          Issue Links

            Activity

              People

              Assignee:
              fgrilli Federico Grilli
              Reporter:
              rgange Richard Gange
              Votes:
              0 Vote for this issue
              Watchers:
              2 Start watching this issue

                Dates

                Created:
                Updated:
                Resolved:
                Date of First Response: