org.artifactory.jcr.lock.LockingException: Write lock on /repositories/libs-snapshots/com/ourproduct not acquired in 120 seconds

There are no available Samebug tips for this exception. Do you have an idea how to solve this issue? A short tip would help users who saw this issue last week.

  • Our buildmachine has multiple threads to build the artifacts. At the end of the build process, the artifact is deployed to the Artifactory server. But the deployment will never finish, all threads are still deploying. After the deadlock occurs, the webinterface is blocked too. Setting the buildmachine to build with one thread, everything is ok. Setting the tomcat of the Artifactory to accept at most 1 thread will not help. Our buildmachine runs CruiseControl and Wagon is used for deployment to the Artifactory. The log on Artifactory shows something like this: org.artifactory.jcr.lock.LockingException: Write lock on /repositories/libs-snapshots/com/ourproduct not acquired in 120 seconds at org.artifactory.jcr.lock.SessionLockEntry.acquire(SessionLockEntry.java:103) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.jcr.lock.SessionLockEntry.acquireWriteLock(SessionLockEntry.java:96) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.jcr.lock.InternalLockManager.writeLock(InternalLockManager.java:60) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.jcr.lock.LockingHelper.writeLock(LockingHelper.java:47) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.internalGetLockedJcrFsItem(JcrRepoBase.java:723) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.internalGetLockedJcrFsItem(JcrRepoBase.java:706) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.getLockedJcrFolder(JcrRepoBase.java:283) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.jcr.fs.JcrFolder.mkdirs(JcrFolder.java:241) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.webdav.WebdavServiceImpl.handleMkcol(WebdavServiceImpl.java:190) [artifactory-core-1.3.0-rc-1.jar:na] at sun.reflect.GeneratedMethodAccessor57.invoke(Unknown Source) [na:na] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [na:1.6.0_03] at java.lang.reflect.Method.invoke(Method.java:597) [na:1.6.0_03] at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:310) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106) [spring-tx-2.5.5.jar:2.5.5] at org.artifactory.jcr.lock.LockingAdvice.invoke(LockingAdvice.java:79) [artifactory-core-1.3.0-rc-1.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) [spring-aop-2.5.5.jar:2.5.5] at $Proxy48.handleMkcol(Unknown Source) [na:na] at org.artifactory.webapp.servlet.RepoFilter.execute(RepoFilter.java:132) [RepoFilter.class:na] at org.artifactory.webapp.servlet.RepoFilter.doFilter(RepoFilter.java:73) [RepoFilter.class:na] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) [catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) [catalina.jar:na] at org.artifactory.webapp.servlet.AccessFilter.useAuthentication(AccessFilter.java:192) [AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.authenticateAndExecute(AccessFilter.java:137) [AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.doFilterInternal(AccessFilter.java:122) [AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.doFilter(AccessFilter.java:91) [AccessFilter.class:na] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) [catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) [catalina.jar:na] at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213) [catalina.jar:na] at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174) [catalina.jar:na] at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) [catalina.jar:na] at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117) [catalina.jar:na] at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108) [catalina.jar:na] at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:151) [catalina.jar:na] at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:874) [tomcat-http.jar:na] at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665) [tomcat-http.jar:na] at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528) [tomcat-util.jar:5.1] at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81) [tomcat-util.jar:5.1] at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:689) [tomcat-util.jar:5.1] at java.lang.Thread.run(Thread.java:619) [na:1.6.0_03] 2008-12-10 10:41:55,959 [ERROR] (o.a.c.c.C.[.[.[.[default]:260) - Servlet.service() for servlet default threw exception org.artifactory.jcr.lock.LockingException: Write lock on /repositories/libs-snapshots/com not acquired in 120 seconds at org.artifactory.jcr.lock.SessionLockEntry.acquire(SessionLockEntry.java:103) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.jcr.lock.SessionLockEntry.acquireWriteLock(SessionLockEntry.java:96) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.jcr.lock.InternalLockManager.writeLock(InternalLockManager.java:60) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.jcr.lock.LockingHelper.writeLock(LockingHelper.java:47) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.internalGetLockedJcrFsItem(JcrRepoBase.java:723) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.internalGetLockedJcrFsItem(JcrRepoBase.java:706) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.getLockedJcrFolder(JcrRepoBase.java:283) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.webdav.WebdavServiceImpl.handleMkcol(WebdavServiceImpl.java:189) [artifactory-core-1.3.0-rc-1.jar:na] at sun.reflect.GeneratedMethodAccessor57.invoke(Unknown Source) [na:na] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [na:1.6.0_03] at java.lang.reflect.Method.invoke(Method.java:597) [na:1.6.0_03] at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:310) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106) [spring-tx-2.5.5.jar:2.5.5] at org.artifactory.jcr.lock.LockingAdvice.invoke(LockingAdvice.java:79) [artifactory-core-1.3.0-rc-1.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) [spring-aop-2.5.5.jar:2.5.5] at $Proxy48.handleMkcol(Unknown Source) [na:na] at org.artifactory.webapp.servlet.RepoFilter.execute(RepoFilter.java:132) [RepoFilter.class:na] at org.artifactory.webapp.servlet.RepoFilter.doFilter(RepoFilter.java:73) [RepoFilter.class:na] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) [catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) [catalina.jar:na] at org.artifactory.webapp.servlet.AccessFilter.useAuthentication(AccessFilter.java:192) [AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.authenticateAndExecute(AccessFilter.java:137) [AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.doFilterInternal(AccessFilter.java:122) [AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.doFilter(AccessFilter.java:91) [AccessFilter.class:na] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) [catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) [catalina.jar:na] at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213) [catalina.jar:na] at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174) [catalina.jar:na] at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) [catalina.jar:na] at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117) [catalina.jar:na] at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108) [catalina.jar:na] at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:151) [catalina.jar:na] at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:874) [tomcat-http.jar:na] at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665) [tomcat-http.jar:na] at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528) [tomcat-util.jar:5.1] at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81) [tomcat-util.jar:5.1] at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:689) [tomcat-util.jar:5.1] at java.lang.Thread.run(Thread.java:619) [na:1.6.0_03]
    via by Cornelis Hoeflake,
  • Our buildmachine has multiple threads to build the artifacts. At the end of the build process, the artifact is deployed to the Artifactory server. But the deployment will never finish, all threads are still deploying. After the deadlock occurs, the webinterface is blocked too. Setting the buildmachine to build with one thread, everything is ok. Setting the tomcat of the Artifactory to accept at most 1 thread will not help. Our buildmachine runs CruiseControl and Wagon is used for deployment to the Artifactory. The log on Artifactory shows something like this: org.artifactory.jcr.lock.LockingException: Write lock on /repositories/libs-snapshots/com/ourproduct not acquired in 120 seconds at org.artifactory.jcr.lock.SessionLockEntry.acquire(SessionLockEntry.java:103) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.jcr.lock.SessionLockEntry.acquireWriteLock(SessionLockEntry.java:96) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.jcr.lock.InternalLockManager.writeLock(InternalLockManager.java:60) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.jcr.lock.LockingHelper.writeLock(LockingHelper.java:47) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.internalGetLockedJcrFsItem(JcrRepoBase.java:723) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.internalGetLockedJcrFsItem(JcrRepoBase.java:706) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.getLockedJcrFolder(JcrRepoBase.java:283) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.jcr.fs.JcrFolder.mkdirs(JcrFolder.java:241) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.webdav.WebdavServiceImpl.handleMkcol(WebdavServiceImpl.java:190) [artifactory-core-1.3.0-rc-1.jar:na] at sun.reflect.GeneratedMethodAccessor57.invoke(Unknown Source) [na:na] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [na:1.6.0_03] at java.lang.reflect.Method.invoke(Method.java:597) [na:1.6.0_03] at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:310) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106) [spring-tx-2.5.5.jar:2.5.5] at org.artifactory.jcr.lock.LockingAdvice.invoke(LockingAdvice.java:79) [artifactory-core-1.3.0-rc-1.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) [spring-aop-2.5.5.jar:2.5.5] at $Proxy48.handleMkcol(Unknown Source) [na:na] at org.artifactory.webapp.servlet.RepoFilter.execute(RepoFilter.java:132) [RepoFilter.class:na] at org.artifactory.webapp.servlet.RepoFilter.doFilter(RepoFilter.java:73) [RepoFilter.class:na] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) [catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) [catalina.jar:na] at org.artifactory.webapp.servlet.AccessFilter.useAuthentication(AccessFilter.java:192) [AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.authenticateAndExecute(AccessFilter.java:137) [AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.doFilterInternal(AccessFilter.java:122) [AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.doFilter(AccessFilter.java:91) [AccessFilter.class:na] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) [catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) [catalina.jar:na] at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213) [catalina.jar:na] at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174) [catalina.jar:na] at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) [catalina.jar:na] at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117) [catalina.jar:na] at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108) [catalina.jar:na] at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:151) [catalina.jar:na] at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:874) [tomcat-http.jar:na] at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665) [tomcat-http.jar:na] at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528) [tomcat-util.jar:5.1] at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81) [tomcat-util.jar:5.1] at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:689) [tomcat-util.jar:5.1] at java.lang.Thread.run(Thread.java:619) [na:1.6.0_03] 2008-12-10 10:41:55,959 [ERROR] (o.a.c.c.C.[.[.[.[default]:260) - Servlet.service() for servlet default threw exception org.artifactory.jcr.lock.LockingException: Write lock on /repositories/libs-snapshots/com not acquired in 120 seconds at org.artifactory.jcr.lock.SessionLockEntry.acquire(SessionLockEntry.java:103) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.jcr.lock.SessionLockEntry.acquireWriteLock(SessionLockEntry.java:96) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.jcr.lock.InternalLockManager.writeLock(InternalLockManager.java:60) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.jcr.lock.LockingHelper.writeLock(LockingHelper.java:47) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.internalGetLockedJcrFsItem(JcrRepoBase.java:723) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.internalGetLockedJcrFsItem(JcrRepoBase.java:706) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.getLockedJcrFolder(JcrRepoBase.java:283) [artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.webdav.WebdavServiceImpl.handleMkcol(WebdavServiceImpl.java:189) [artifactory-core-1.3.0-rc-1.jar:na] at sun.reflect.GeneratedMethodAccessor57.invoke(Unknown Source) [na:na] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [na:1.6.0_03] at java.lang.reflect.Method.invoke(Method.java:597) [na:1.6.0_03] at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:310) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106) [spring-tx-2.5.5.jar:2.5.5] at org.artifactory.jcr.lock.LockingAdvice.invoke(LockingAdvice.java:79) [artifactory-core-1.3.0-rc-1.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) [spring-aop-2.5.5.jar:2.5.5] at $Proxy48.handleMkcol(Unknown Source) [na:na] at org.artifactory.webapp.servlet.RepoFilter.execute(RepoFilter.java:132) [RepoFilter.class:na] at org.artifactory.webapp.servlet.RepoFilter.doFilter(RepoFilter.java:73) [RepoFilter.class:na] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) [catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) [catalina.jar:na] at org.artifactory.webapp.servlet.AccessFilter.useAuthentication(AccessFilter.java:192) [AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.authenticateAndExecute(AccessFilter.java:137) [AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.doFilterInternal(AccessFilter.java:122) [AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.doFilter(AccessFilter.java:91) [AccessFilter.class:na] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) [catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) [catalina.jar:na] at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213) [catalina.jar:na] at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174) [catalina.jar:na] at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) [catalina.jar:na] at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117) [catalina.jar:na] at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108) [catalina.jar:na] at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:151) [catalina.jar:na] at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:874) [tomcat-http.jar:na] at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665) [tomcat-http.jar:na] at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528) [tomcat-util.jar:5.1] at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81) [tomcat-util.jar:5.1] at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:689) [tomcat-util.jar:5.1] at java.lang.Thread.run(Thread.java:619) [na:1.6.0_03]
    via by Cornelis Hoeflake,
  • I just upgraded to 2.0.0, and I occasionally get the following error in the Artifactory log, and my deployment on the client side fails. (The client is a Hudson instance performing a build with the "Deploy artifacts to Maven repository" option enabled, if you happen to be familiar.) This seems to happen whenever I have multiple builds attempting to do deployments all at the same time, many of which are large WARs, which take a long time to upload, especially when the Artifactory server (which doubles as the Hudson master) is under moderate load. The lock that it fails to acquire is always on "/repositories/private-internal-local/com". Does this mean that it's trying to get a lock on each of the directories in the tree for each artifact? And does it hold on to those locks throughout the entire upload? This is a major problem because it means that all of our artifact deployments will bottleneck on acquiring the lock on "com" (since all of our artifacts' groupIds begin with "com"), and large uploads like WARs only make it worse. By the way, apart from this problem, Artifactory 2.0.0 looks awesome and works great. Thanks a lot. 2009-01-20 15:58:29,101 [ERROR] (o.a.w.s.RepoFilter :72) - Sending HTTP error code 500: Failed to save resource 'private-internal-local:com/***SNIP***'. java.lang.RuntimeException: Failed to save resource 'private-internal-local:com/***SNIP***'. at org.artifactory.repo.jcr.JcrRepoBase.saveResource(JcrRepoBase.java:606) [artifactory-core-2.0.0.jar:na] at org.artifactory.engine.UploadServiceImpl.doProcess(UploadServiceImpl.java:154) [artifactory-core-2.0.0.jar:na] at sun.reflect.GeneratedMethodAccessor130.invoke(Unknown Source) [na:na] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [na:1.5.0_06] at java.lang.reflect.Method.invoke(Method.java:585) [na:1.5.0_06] at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:310) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106) [spring-tx-2.5.5.jar:2.5.5] at org.artifactory.jcr.lock.LockingAdvice.invoke(LockingAdvice.java:79) [artifactory-core-2.0.0.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) [spring-aop-2.5.5.jar:2.5.5] at $Proxy45.doProcess(Unknown Source) [na:na] at org.artifactory.engine.UploadServiceImpl.process(UploadServiceImpl.java:105) [artifactory-core-2.0.0.jar:na] at sun.reflect.GeneratedMethodAccessor127.invoke(Unknown Source) [na:na] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [na:1.5.0_06] at java.lang.reflect.Method.invoke(Method.java:585) [na:1.5.0_06] at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:310) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) [spring-aop-2.5.5.jar:2.5.5] at $Proxy45.process(Unknown Source) [na:na] at org.artifactory.webapp.servlet.RepoFilter.execute(RepoFilter.java:121) [RepoFilter.class:na] at org.artifactory.webapp.servlet.RepoFilter.doFilter(RepoFilter.java:73) [RepoFilter.class:na] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202) [catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173) [catalina.jar:na] at org.artifactory.webapp.servlet.AccessFilter.useAuthentication(AccessFilter.java:190) [AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.authenticateAndExecute(AccessFilter.java:135) [AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.doFilterInternal(AccessFilter.java:120) [AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.doFilter(AccessFilter.java:90) [AccessFilter.class:na] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202) [catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173) [catalina.jar:na] at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213) [catalina.jar:na] at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178) [catalina.jar:na] at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126) [catalina.jar:na] at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105) [catalina.jar:na] at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:541) [catalina.jar:na] at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107) [catalina.jar:na] at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148) [catalina.jar:na] at org.apache.jk.server.JkCoyoteHandler.invoke(JkCoyoteHandler.java:199) [tomcat-ajp.jar:na] at org.apache.jk.common.HandlerRequest.invoke(HandlerRequest.java:282) [tomcat-ajp.jar:na] at org.apache.jk.common.ChannelSocket.invoke(ChannelSocket.java:754) [tomcat-ajp.jar:na] at org.apache.jk.common.ChannelSocket.processConnection(ChannelSocket.java:684) [tomcat-ajp.jar:na] at org.apache.jk.common.ChannelSocket$SocketConnection.runIt(ChannelSocket.java:876) [tomcat-ajp.jar:na] at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:684) [tomcat-util.jar:5.1] at java.lang.Thread.run(Thread.java:595) [na:1.5.0_06] Caused by: org.artifactory.jcr.lock.LockingException: Write lock on /repositories/private-internal-local/com not acquired in 120 seconds at org.artifactory.jcr.lock.SessionLockEntry.acquire(SessionLockEntry.java:103) [artifactory-core-2.0.0.jar:na] at org.artifactory.jcr.lock.SessionLockEntry.acquireWriteLock(SessionLockEntry.java:96) [artifactory-core-2.0.0.jar:na] at org.artifactory.jcr.lock.InternalLockManager.writeLock(InternalLockManager.java:60) [artifactory-core-2.0.0.jar:na] at org.artifactory.jcr.lock.LockingHelper.writeLock(LockingHelper.java:47) [artifactory-core-2.0.0.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.internalGetLockedJcrFsItem(JcrRepoBase.java:744) [artifactory-core-2.0.0.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.internalGetLockedJcrFsItem(JcrRepoBase.java:726) [artifactory-core-2.0.0.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.getLockedJcrFolder(JcrRepoBase.java:289) [artifactory-core-2.0.0.jar:na] at org.artifactory.jcr.fs.JcrFolder.mkdirs(JcrFolder.java:257) [artifactory-core-2.0.0.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.saveResource(JcrRepoBase.java:579) [artifactory-core-2.0.0.jar:na] ... 43 common frames omitted
    via by Jonathan Kelly,
  • I just upgraded to 2.0.0, and I occasionally get the following error in the Artifactory log, and my deployment on the client side fails. (The client is a Hudson instance performing a build with the "Deploy artifacts to Maven repository" option enabled, if you happen to be familiar.) This seems to happen whenever I have multiple builds attempting to do deployments all at the same time, many of which are large WARs, which take a long time to upload, especially when the Artifactory server (which doubles as the Hudson master) is under moderate load. The lock that it fails to acquire is always on "/repositories/private-internal-local/com". Does this mean that it's trying to get a lock on each of the directories in the tree for each artifact? And does it hold on to those locks throughout the entire upload? This is a major problem because it means that all of our artifact deployments will bottleneck on acquiring the lock on "com" (since all of our artifacts' groupIds begin with "com"), and large uploads like WARs only make it worse. By the way, apart from this problem, Artifactory 2.0.0 looks awesome and works great. Thanks a lot. 2009-01-20 15:58:29,101 [ERROR] (o.a.w.s.RepoFilter :72) - Sending HTTP error code 500: Failed to save resource 'private-internal-local:com/***SNIP***'. java.lang.RuntimeException: Failed to save resource 'private-internal-local:com/***SNIP***'. at org.artifactory.repo.jcr.JcrRepoBase.saveResource(JcrRepoBase.java:606) [artifactory-core-2.0.0.jar:na] at org.artifactory.engine.UploadServiceImpl.doProcess(UploadServiceImpl.java:154) [artifactory-core-2.0.0.jar:na] at sun.reflect.GeneratedMethodAccessor130.invoke(Unknown Source) [na:na] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [na:1.5.0_06] at java.lang.reflect.Method.invoke(Method.java:585) [na:1.5.0_06] at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:310) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106) [spring-tx-2.5.5.jar:2.5.5] at org.artifactory.jcr.lock.LockingAdvice.invoke(LockingAdvice.java:79) [artifactory-core-2.0.0.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) [spring-aop-2.5.5.jar:2.5.5] at $Proxy45.doProcess(Unknown Source) [na:na] at org.artifactory.engine.UploadServiceImpl.process(UploadServiceImpl.java:105) [artifactory-core-2.0.0.jar:na] at sun.reflect.GeneratedMethodAccessor127.invoke(Unknown Source) [na:na] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [na:1.5.0_06] at java.lang.reflect.Method.invoke(Method.java:585) [na:1.5.0_06] at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:310) [spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) [spring-aop-2.5.5.jar:2.5.5] at $Proxy45.process(Unknown Source) [na:na] at org.artifactory.webapp.servlet.RepoFilter.execute(RepoFilter.java:121) [RepoFilter.class:na] at org.artifactory.webapp.servlet.RepoFilter.doFilter(RepoFilter.java:73) [RepoFilter.class:na] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202) [catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173) [catalina.jar:na] at org.artifactory.webapp.servlet.AccessFilter.useAuthentication(AccessFilter.java:190) [AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.authenticateAndExecute(AccessFilter.java:135) [AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.doFilterInternal(AccessFilter.java:120) [AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.doFilter(AccessFilter.java:90) [AccessFilter.class:na] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202) [catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173) [catalina.jar:na] at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213) [catalina.jar:na] at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178) [catalina.jar:na] at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126) [catalina.jar:na] at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105) [catalina.jar:na] at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:541) [catalina.jar:na] at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107) [catalina.jar:na] at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148) [catalina.jar:na] at org.apache.jk.server.JkCoyoteHandler.invoke(JkCoyoteHandler.java:199) [tomcat-ajp.jar:na] at org.apache.jk.common.HandlerRequest.invoke(HandlerRequest.java:282) [tomcat-ajp.jar:na] at org.apache.jk.common.ChannelSocket.invoke(ChannelSocket.java:754) [tomcat-ajp.jar:na] at org.apache.jk.common.ChannelSocket.processConnection(ChannelSocket.java:684) [tomcat-ajp.jar:na] at org.apache.jk.common.ChannelSocket$SocketConnection.runIt(ChannelSocket.java:876) [tomcat-ajp.jar:na] at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:684) [tomcat-util.jar:5.1] at java.lang.Thread.run(Thread.java:595) [na:1.5.0_06] Caused by: org.artifactory.jcr.lock.LockingException: Write lock on /repositories/private-internal-local/com not acquired in 120 seconds at org.artifactory.jcr.lock.SessionLockEntry.acquire(SessionLockEntry.java:103) [artifactory-core-2.0.0.jar:na] at org.artifactory.jcr.lock.SessionLockEntry.acquireWriteLock(SessionLockEntry.java:96) [artifactory-core-2.0.0.jar:na] at org.artifactory.jcr.lock.InternalLockManager.writeLock(InternalLockManager.java:60) [artifactory-core-2.0.0.jar:na] at org.artifactory.jcr.lock.LockingHelper.writeLock(LockingHelper.java:47) [artifactory-core-2.0.0.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.internalGetLockedJcrFsItem(JcrRepoBase.java:744) [artifactory-core-2.0.0.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.internalGetLockedJcrFsItem(JcrRepoBase.java:726) [artifactory-core-2.0.0.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.getLockedJcrFolder(JcrRepoBase.java:289) [artifactory-core-2.0.0.jar:na] at org.artifactory.jcr.fs.JcrFolder.mkdirs(JcrFolder.java:257) [artifactory-core-2.0.0.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.saveResource(JcrRepoBase.java:579) [artifactory-core-2.0.0.jar:na] ... 43 common frames omitted
    via by Jonathan Kelly,
    • org.artifactory.jcr.lock.LockingException: Write lock on /repositories/libs-snapshots/com/ourproduct not acquired in 120 seconds at org.artifactory.jcr.lock.SessionLockEntry.acquire(SessionLockEntry.java:103)[artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.jcr.lock.SessionLockEntry.acquireWriteLock(SessionLockEntry.java:96)[artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.jcr.lock.InternalLockManager.writeLock(InternalLockManager.java:60)[artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.jcr.lock.LockingHelper.writeLock(LockingHelper.java:47)[artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.internalGetLockedJcrFsItem(JcrRepoBase.java:723)[artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.internalGetLockedJcrFsItem(JcrRepoBase.java:706)[artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.jcr.JcrRepoBase.getLockedJcrFolder(JcrRepoBase.java:283)[artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.jcr.fs.JcrFolder.mkdirs(JcrFolder.java:241)[artifactory-core-1.3.0-rc-1.jar:na] at org.artifactory.repo.webdav.WebdavServiceImpl.handleMkcol(WebdavServiceImpl.java:190)[artifactory-core-1.3.0-rc-1.jar:na] at sun.reflect.GeneratedMethodAccessor57.invoke(Unknown Source)[na:na] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)[na:1.6.0_03] at java.lang.reflect.Method.invoke(Method.java:597)[na:1.6.0_03] at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:310)[spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182)[spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149)[spring-aop-2.5.5.jar:2.5.5] at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106)[spring-tx-2.5.5.jar:2.5.5] at org.artifactory.jcr.lock.LockingAdvice.invoke(LockingAdvice.java:79)[artifactory-core-1.3.0-rc-1.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)[spring-aop-2.5.5.jar:2.5.5] at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)[spring-aop-2.5.5.jar:2.5.5] at $Proxy48.handleMkcol(Unknown Source)[na:na] at org.artifactory.webapp.servlet.RepoFilter.execute(RepoFilter.java:132)[RepoFilter.class:na] at org.artifactory.webapp.servlet.RepoFilter.doFilter(RepoFilter.java:73)[RepoFilter.class:na] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)[catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)[catalina.jar:na] at org.artifactory.webapp.servlet.AccessFilter.useAuthentication(AccessFilter.java:192)[AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.authenticateAndExecute(AccessFilter.java:137)[AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.doFilterInternal(AccessFilter.java:122)[AccessFilter.class:na] at org.artifactory.webapp.servlet.AccessFilter.doFilter(AccessFilter.java:91)[AccessFilter.class:na] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)[catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)[catalina.jar:na] at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)[catalina.jar:na] at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174)[catalina.jar:na] at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)[catalina.jar:na] at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117)[catalina.jar:na] at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108)[catalina.jar:na] at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:151)[catalina.jar:na] at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:874)[tomcat-http.jar:na] at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665)[tomcat-http.jar:na] at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528)[tomcat-util.jar:5.1] at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81)[tomcat-util.jar:5.1] at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:689)[tomcat-util.jar:5.1] at java.lang.Thread.run(Thread.java:619)[na:1.6.0_03]
    No Bugmate found.