-
Improvement
-
Resolution: Done
-
Neutral
-
1.1.5, 1.2.1
-
-
Empty show more show less
-
Maintenance 22
-
1
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]
Acceptance criteria
- is related to
-
EEPUBLISH-28 Recurring Problem With Node Locking After Publishing is Causing Closed Sessions
- Closed
-
EEPUBLISH-29 Add extra debug logging
- Closed
-
PUBLISHING-86 Provide an app to clear activation locks
- Closed
- mentioned in
-
Page Loading...