exceptionorg.artifactory.storage.fs.VfsException

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.

  • {code} Jul 11, 2013 1:08:23 AM org.apache.catalina.core.StandardWrapperValve invokeSEVERE: Servlet.service() for servlet [default] in context with path [/artifactory] threw exceptionorg.artifactory.storage.fs.VfsException: Failed to delete stats from node id 793346 at org.artifactory.storage.db.fs.service.StatsServiceImpl.deleteStats(StatsServiceImpl.java:130) at sun.reflect.GeneratedMethodAccessor212.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy48.deleteStats(Unknown Source) at org.artifactory.storage.db.fs.model.DbMutableFile.doDeleteInternal(DbMutableFile.java:173) at org.artifactory.storage.db.fs.model.DbMutableItem.save(DbMutableItem.java:209) at org.artifactory.storage.fs.lock.SessionLockEntry.save(SessionLockEntry.java:71) at org.artifactory.storage.db.fs.session.SqlStorageSession.save(SqlStorageSession.java:66) at org.artifactory.storage.db.spring.SessionSynchronization.beforeCommit(SessionSynchronization.java:74) at org.springframework.transaction.support.TransactionSynchronizationUtils.triggerBeforeCommit(TransactionSynchronizationUtils.java:95) at org.springframework.transaction.support.AbstractPlatformTransactionManager.triggerBeforeCommit(AbstractPlatformTransactionManager.java:924) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:737) at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:723) at org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:392) at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:120) at org.artifactory.storage.fs.lock.aop.LockingAdvice.invoke(LockingAdvice.java:64) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) at com.sun.proxy.$Proxy35.undeploy(Unknown Source) at org.artifactory.repo.webdav.WebdavServiceImpl.deleteItem(WebdavServiceImpl.java:276) at org.artifactory.repo.webdav.WebdavServiceImpl.handleDelete(WebdavServiceImpl.java:269) at sun.reflect.GeneratedMethodAccessor219.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy73.handleDelete(Unknown Source) at org.artifactory.webapp.servlet.RepoFilter.doWebDavMethod(RepoFilter.java:185) at org.artifactory.webapp.servlet.RepoFilter.execute(RepoFilter.java:150) at org.artifactory.webapp.servlet.RepoFilter.doFilter(RepoFilter.java:85) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:243) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:210) at org.artifactory.webapp.servlet.AccessFilter.useAuthentication(AccessFilter.java:272) at org.artifactory.webapp.servlet.AccessFilter.authenticateAndExecute(AccessFilter.java:193) at org.artifactory.webapp.servlet.AccessFilter.doFilterInternal(AccessFilter.java:175) at org.artifactory.webapp.servlet.AccessFilter.doFilter(AccessFilter.java:143) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:243) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:210) at org.artifactory.webapp.servlet.RequestFilter.doFilter(RequestFilter.java:57) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:243) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:210) at org.artifactory.webapp.servlet.ArtifactoryFilter.doFilter(ArtifactoryFilter.java:72) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:243) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:210) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:222) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:123) at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:472) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:171) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:99) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:118) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408) at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1009) at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:589) at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:310) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:722) Caused by: com.mysql.jdbc.exceptions.jdbc4.MySQLTransactionRollbackException: Deadlock found when trying to get lock; try restarting transaction at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) at java.lang.reflect.Constructor.newInstance(Constructor.java:525) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.Util.getInstance(Util.java:386) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1065) at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4096) at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4028) at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2490) at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2651) at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2734) at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2155) at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2458) at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2375) at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2359) at org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:105) at org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:105) at org.artifactory.storage.db.util.JdbcHelper.executeUpdate(JdbcHelper.java:125) at org.artifactory.storage.db.fs.dao.StatsDao.deleteStats(StatsDao.java:77) at org.artifactory.storage.db.fs.service.StatsServiceImpl.deleteStats(StatsServiceImpl.java:127) ... 59 moreI tracked this down from the following error:2013-07-10 18:08:24,276 ERROR <html><head><title>Apache Tomcat/7.0.39 - Error report</title><style><!--H1 {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;font-size:22px;} H2 {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;font-size:16px;} H3 {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;font-size:14px;} BODY {font-family:Tahoma,Arial,sans-serif;color:black;background-color:white;} B {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;} P {font-family:Tahoma,Arial,sans-serif;background:white;color:black;font-size:12px;}A {color : black;}A.name {color : black;}HR {color : #525D76;}--></style> </head><body><h1>HTTP Status 500 - Failed to delete stats from node id 793346</h1><HR size="1" noshade="noshade"><p><b>type</b> Exception report</p><p><b>message</b> <u>Failed to delete stats from node id 793346</u></p><p><b>description</b> <u>The server encountered an internal error that prevented it from fulfilling this request.</u></p><p><b>exception</b> <pre>org.artifactory.storage.fs.VfsException: Failed to delete stats from node id 793346 org.artifactory.storage.db.fs.service.StatsServiceImpl.deleteStats(StatsServiceImpl.java:130) sun.reflect.GeneratedMethodAccessor212.invoke(Unknown Source) sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) java.lang.reflect.Method.invoke(Method.java:601) org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) com.sun.proxy.$Proxy48.deleteStats(Unknown Source) org.artifactory.storage.db.fs.model.DbMutableFile.doDeleteInternal(DbMutableFile.java:173) org.artifactory.storage.db.fs.model.DbMutableItem.save(DbMutableItem.java:209) org.artifactory.storage.fs.lock.SessionLockEntry.save(SessionLockEntry.java:71) org.artifactory.storage.db.fs.session.SqlStorageSession.save(SqlStorageSession.java:66) org.artifactory.storage.db.spring.SessionSynchronization.beforeCommit(SessionSynchronization.java:74) org.springframework.transaction.support.TransactionSynchronizationUtils.triggerBeforeCommit(TransactionSynchronizationUtils.java:95) org.springframework.transaction.support.AbstractPlatformTransactionManager.triggerBeforeCommit(AbstractPlatformTransactionManager.java:924) org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:737) org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:723) org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:392) org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:120) org.artifactory.storage.fs.lock.aop.LockingAdvice.invoke(LockingAdvice.java:64) org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) com.sun.proxy.$Proxy35.undeploy(Unknown Source) org.artifactory.repo.webdav.WebdavServiceImpl.deleteItem(WebdavServiceImpl.java:276) org.artifactory.repo.webdav.WebdavServiceImpl.handleDelete(WebdavServiceImpl.java:269) sun.reflect.GeneratedMethodAccessor219.invoke(Unknown Source) sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) java.lang.reflect.Method.invoke(Method.java:601) org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) com.sun.proxy.$Proxy73.handleDelete(Unknown Source) org.artifactory.webapp.servlet.RepoFilter.doWebDavMethod(RepoFilter.java:185) org.artifactory.webapp.servlet.RepoFilter.execute(RepoFilter.java:150) org.artifactory.webapp.servlet.RepoFilter.doFilter(RepoFilter.java:85) org.artifactory.webapp.servlet.AccessFilter.useAuthentication(AccessFilter.java:272) org.artifactory.webapp.servlet.AccessFilter.authenticateAndExecute(AccessFilter.java:193) org.artifactory.webapp.servlet.AccessFilter.doFilterInternal(AccessFilter.java:175) org.artifactory.webapp.servlet.AccessFilter.doFilter(AccessFilter.java:143) org.artifactory.webapp.servlet.RequestFilter.doFilter(RequestFilter.java:57) org.artifactory.webapp.servlet.ArtifactoryFilter.doFilter(ArtifactoryFilter.java:72)</pre></p><p><b>root cause</b> <pre>com.mysql.jdbc.exceptions.jdbc4.MySQLTransactionRollbackException: Deadlock found when trying to get lock; try restarting transaction sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57) sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) java.lang.reflect.Constructor.newInstance(Constructor.java:525) com.mysql.jdbc.Util.handleNewInstance(Util.java:411) com.mysql.jdbc.Util.getInstance(Util.java:386) com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1065) com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4096) com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4028) com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2490) com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2651) com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2734) com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2155) com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2458) com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2375) com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2359) org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:105) org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:105) org.artifactory.storage.db.util.JdbcHelper.executeUpdate(JdbcHelper.java:125) org.artifactory.storage.db.fs.dao.StatsDao.deleteStats(StatsDao.java:77) org.artifactory.storage.db.fs.service.StatsServiceImpl.deleteStats(StatsServiceImpl.java:127) sun.reflect.GeneratedMethodAccessor212.invoke(Unknown Source) sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) java.lang.reflect.Method.invoke(Method.java:601) org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) com.sun.proxy.$Proxy48.deleteStats(Unknown Source) org.artifactory.storage.db.fs.model.DbMutableFile.doDeleteInternal(DbMutableFile.java:173) org.artifactory.storage.db.fs.model.DbMutableItem.save(DbMutableItem.java:209) org.artifactory.storage.fs.lock.SessionLockEntry.save(SessionLockEntry.java:71) org.artifactory.storage.db.fs.session.SqlStorageSession.save(SqlStorageSession.java:66) org.artifactory.storage.db.spring.SessionSynchronization.beforeCommit(SessionSynchronization.java:74) org.springframework.transaction.support.TransactionSynchronizationUtils.triggerBeforeCommit(TransactionSynchronizationUtils.java:95) org.springframework.transaction.support.AbstractPlatformTransactionManager.triggerBeforeCommit(AbstractPlatformTransactionManager.java:924) org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:737) org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:723) org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:392) org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:120) org.artifactory.storage.fs.lock.aop.LockingAdvice.invoke(LockingAdvice.java:64) org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) com.sun.proxy.$Proxy35.undeploy(Unknown Source) org.artifactory.repo.webdav.WebdavServiceImpl.deleteItem(WebdavServiceImpl.java:276) org.artifactory.repo.webdav.WebdavServiceImpl.handleDelete(WebdavServiceImpl.java:269) sun.reflect.GeneratedMethodAccessor219.invoke(Unknown Source) sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) java.lang.reflect.Method.invoke(Method.java:601) org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) com.sun.proxy.$Proxy73.handleDelete(Unknown Source) org.artifactory.webapp.servlet.RepoFilter.doWebDavMethod(RepoFilter.java:185) org.artifactory.webapp.servlet.RepoFilter.execute(RepoFilter.java:150) org.artifactory.webapp.servlet.RepoFilter.doFilter(RepoFilter.java:85) org.artifactory.webapp.servlet.AccessFilter.useAuthentication(AccessFilter.java:272) org.artifactory.webapp.servlet.AccessFilter.authenticateAndExecute(AccessFilter.java:193) org.artifactory.webapp.servlet.AccessFilter.doFilterInternal(AccessFilter.java:175) org.artifactory.webapp.servlet.AccessFilter.doFilter(AccessFilter.java:143) org.artifactory.webapp.servlet.RequestFilter.doFilter(RequestFilter.java:57) org.artifactory.webapp.servlet.ArtifactoryFilter.doFilter(ArtifactoryFilter.java:72)</pre></p><p><b>note</b> <u>The full stack trace of the root cause is available in the Apache Tomcat/7.0.39 logs.</u></p><HR size="1" noshade="noshade"><h3>Apache Tomcat/7.0.39</h3></body></html> {code}
    via by Frederic Simon,
  • {code} Jul 11, 2013 1:08:23 AM org.apache.catalina.core.StandardWrapperValve invokeSEVERE: Servlet.service() for servlet [default] in context with path [/artifactory] threw exceptionorg.artifactory.storage.fs.VfsException: Failed to delete stats from node id 793346 at org.artifactory.storage.db.fs.service.StatsServiceImpl.deleteStats(StatsServiceImpl.java:130) at sun.reflect.GeneratedMethodAccessor212.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy48.deleteStats(Unknown Source) at org.artifactory.storage.db.fs.model.DbMutableFile.doDeleteInternal(DbMutableFile.java:173) at org.artifactory.storage.db.fs.model.DbMutableItem.save(DbMutableItem.java:209) at org.artifactory.storage.fs.lock.SessionLockEntry.save(SessionLockEntry.java:71) at org.artifactory.storage.db.fs.session.SqlStorageSession.save(SqlStorageSession.java:66) at org.artifactory.storage.db.spring.SessionSynchronization.beforeCommit(SessionSynchronization.java:74) at org.springframework.transaction.support.TransactionSynchronizationUtils.triggerBeforeCommit(TransactionSynchronizationUtils.java:95) at org.springframework.transaction.support.AbstractPlatformTransactionManager.triggerBeforeCommit(AbstractPlatformTransactionManager.java:924) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:737) at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:723) at org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:392) at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:120) at org.artifactory.storage.fs.lock.aop.LockingAdvice.invoke(LockingAdvice.java:64) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) at com.sun.proxy.$Proxy35.undeploy(Unknown Source) at org.artifactory.repo.webdav.WebdavServiceImpl.deleteItem(WebdavServiceImpl.java:276) at org.artifactory.repo.webdav.WebdavServiceImpl.handleDelete(WebdavServiceImpl.java:269) at sun.reflect.GeneratedMethodAccessor219.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy73.handleDelete(Unknown Source) at org.artifactory.webapp.servlet.RepoFilter.doWebDavMethod(RepoFilter.java:185) at org.artifactory.webapp.servlet.RepoFilter.execute(RepoFilter.java:150) at org.artifactory.webapp.servlet.RepoFilter.doFilter(RepoFilter.java:85) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:243) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:210) at org.artifactory.webapp.servlet.AccessFilter.useAuthentication(AccessFilter.java:272) at org.artifactory.webapp.servlet.AccessFilter.authenticateAndExecute(AccessFilter.java:193) at org.artifactory.webapp.servlet.AccessFilter.doFilterInternal(AccessFilter.java:175) at org.artifactory.webapp.servlet.AccessFilter.doFilter(AccessFilter.java:143) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:243) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:210) at org.artifactory.webapp.servlet.RequestFilter.doFilter(RequestFilter.java:57) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:243) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:210) at org.artifactory.webapp.servlet.ArtifactoryFilter.doFilter(ArtifactoryFilter.java:72) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:243) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:210) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:222) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:123) at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:472) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:171) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:99) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:118) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408) at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1009) at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:589) at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:310) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:722) Caused by: com.mysql.jdbc.exceptions.jdbc4.MySQLTransactionRollbackException: Deadlock found when trying to get lock; try restarting transaction at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) at java.lang.reflect.Constructor.newInstance(Constructor.java:525) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.Util.getInstance(Util.java:386) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1065) at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4096) at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4028) at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2490) at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2651) at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2734) at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2155) at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2458) at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2375) at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2359) at org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:105) at org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:105) at org.artifactory.storage.db.util.JdbcHelper.executeUpdate(JdbcHelper.java:125) at org.artifactory.storage.db.fs.dao.StatsDao.deleteStats(StatsDao.java:77) at org.artifactory.storage.db.fs.service.StatsServiceImpl.deleteStats(StatsServiceImpl.java:127) ... 59 moreI tracked this down from the following error:2013-07-10 18:08:24,276 ERROR <html><head><title>Apache Tomcat/7.0.39 - Error report</title><style><!--H1 {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;font-size:22px;} H2 {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;font-size:16px;} H3 {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;font-size:14px;} BODY {font-family:Tahoma,Arial,sans-serif;color:black;background-color:white;} B {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;} P {font-family:Tahoma,Arial,sans-serif;background:white;color:black;font-size:12px;}A {color : black;}A.name {color : black;}HR {color : #525D76;}--></style> </head><body><h1>HTTP Status 500 - Failed to delete stats from node id 793346</h1><HR size="1" noshade="noshade"><p><b>type</b> Exception report</p><p><b>message</b> <u>Failed to delete stats from node id 793346</u></p><p><b>description</b> <u>The server encountered an internal error that prevented it from fulfilling this request.</u></p><p><b>exception</b> <pre>org.artifactory.storage.fs.VfsException: Failed to delete stats from node id 793346 org.artifactory.storage.db.fs.service.StatsServiceImpl.deleteStats(StatsServiceImpl.java:130) sun.reflect.GeneratedMethodAccessor212.invoke(Unknown Source) sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) java.lang.reflect.Method.invoke(Method.java:601) org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) com.sun.proxy.$Proxy48.deleteStats(Unknown Source) org.artifactory.storage.db.fs.model.DbMutableFile.doDeleteInternal(DbMutableFile.java:173) org.artifactory.storage.db.fs.model.DbMutableItem.save(DbMutableItem.java:209) org.artifactory.storage.fs.lock.SessionLockEntry.save(SessionLockEntry.java:71) org.artifactory.storage.db.fs.session.SqlStorageSession.save(SqlStorageSession.java:66) org.artifactory.storage.db.spring.SessionSynchronization.beforeCommit(SessionSynchronization.java:74) org.springframework.transaction.support.TransactionSynchronizationUtils.triggerBeforeCommit(TransactionSynchronizationUtils.java:95) org.springframework.transaction.support.AbstractPlatformTransactionManager.triggerBeforeCommit(AbstractPlatformTransactionManager.java:924) org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:737) org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:723) org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:392) org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:120) org.artifactory.storage.fs.lock.aop.LockingAdvice.invoke(LockingAdvice.java:64) org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) com.sun.proxy.$Proxy35.undeploy(Unknown Source) org.artifactory.repo.webdav.WebdavServiceImpl.deleteItem(WebdavServiceImpl.java:276) org.artifactory.repo.webdav.WebdavServiceImpl.handleDelete(WebdavServiceImpl.java:269) sun.reflect.GeneratedMethodAccessor219.invoke(Unknown Source) sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) java.lang.reflect.Method.invoke(Method.java:601) org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) com.sun.proxy.$Proxy73.handleDelete(Unknown Source) org.artifactory.webapp.servlet.RepoFilter.doWebDavMethod(RepoFilter.java:185) org.artifactory.webapp.servlet.RepoFilter.execute(RepoFilter.java:150) org.artifactory.webapp.servlet.RepoFilter.doFilter(RepoFilter.java:85) org.artifactory.webapp.servlet.AccessFilter.useAuthentication(AccessFilter.java:272) org.artifactory.webapp.servlet.AccessFilter.authenticateAndExecute(AccessFilter.java:193) org.artifactory.webapp.servlet.AccessFilter.doFilterInternal(AccessFilter.java:175) org.artifactory.webapp.servlet.AccessFilter.doFilter(AccessFilter.java:143) org.artifactory.webapp.servlet.RequestFilter.doFilter(RequestFilter.java:57) org.artifactory.webapp.servlet.ArtifactoryFilter.doFilter(ArtifactoryFilter.java:72)</pre></p><p><b>root cause</b> <pre>com.mysql.jdbc.exceptions.jdbc4.MySQLTransactionRollbackException: Deadlock found when trying to get lock; try restarting transaction sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57) sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) java.lang.reflect.Constructor.newInstance(Constructor.java:525) com.mysql.jdbc.Util.handleNewInstance(Util.java:411) com.mysql.jdbc.Util.getInstance(Util.java:386) com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1065) com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4096) com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4028) com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2490) com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2651) com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2734) com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2155) com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2458) com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2375) com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2359) org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:105) org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:105) org.artifactory.storage.db.util.JdbcHelper.executeUpdate(JdbcHelper.java:125) org.artifactory.storage.db.fs.dao.StatsDao.deleteStats(StatsDao.java:77) org.artifactory.storage.db.fs.service.StatsServiceImpl.deleteStats(StatsServiceImpl.java:127) sun.reflect.GeneratedMethodAccessor212.invoke(Unknown Source) sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) java.lang.reflect.Method.invoke(Method.java:601) org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) com.sun.proxy.$Proxy48.deleteStats(Unknown Source) org.artifactory.storage.db.fs.model.DbMutableFile.doDeleteInternal(DbMutableFile.java:173) org.artifactory.storage.db.fs.model.DbMutableItem.save(DbMutableItem.java:209) org.artifactory.storage.fs.lock.SessionLockEntry.save(SessionLockEntry.java:71) org.artifactory.storage.db.fs.session.SqlStorageSession.save(SqlStorageSession.java:66) org.artifactory.storage.db.spring.SessionSynchronization.beforeCommit(SessionSynchronization.java:74) org.springframework.transaction.support.TransactionSynchronizationUtils.triggerBeforeCommit(TransactionSynchronizationUtils.java:95) org.springframework.transaction.support.AbstractPlatformTransactionManager.triggerBeforeCommit(AbstractPlatformTransactionManager.java:924) org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:737) org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:723) org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:392) org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:120) org.artifactory.storage.fs.lock.aop.LockingAdvice.invoke(LockingAdvice.java:64) org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) com.sun.proxy.$Proxy35.undeploy(Unknown Source) org.artifactory.repo.webdav.WebdavServiceImpl.deleteItem(WebdavServiceImpl.java:276) org.artifactory.repo.webdav.WebdavServiceImpl.handleDelete(WebdavServiceImpl.java:269) sun.reflect.GeneratedMethodAccessor219.invoke(Unknown Source) sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) java.lang.reflect.Method.invoke(Method.java:601) org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) com.sun.proxy.$Proxy73.handleDelete(Unknown Source) org.artifactory.webapp.servlet.RepoFilter.doWebDavMethod(RepoFilter.java:185) org.artifactory.webapp.servlet.RepoFilter.execute(RepoFilter.java:150) org.artifactory.webapp.servlet.RepoFilter.doFilter(RepoFilter.java:85) org.artifactory.webapp.servlet.AccessFilter.useAuthentication(AccessFilter.java:272) org.artifactory.webapp.servlet.AccessFilter.authenticateAndExecute(AccessFilter.java:193) org.artifactory.webapp.servlet.AccessFilter.doFilterInternal(AccessFilter.java:175) org.artifactory.webapp.servlet.AccessFilter.doFilter(AccessFilter.java:143) org.artifactory.webapp.servlet.RequestFilter.doFilter(RequestFilter.java:57) org.artifactory.webapp.servlet.ArtifactoryFilter.doFilter(ArtifactoryFilter.java:72)</pre></p><p><b>note</b> <u>The full stack trace of the root cause is available in the Apache Tomcat/7.0.39 logs.</u></p><HR size="1" noshade="noshade"><h3>Apache Tomcat/7.0.39</h3></body></html> {code}
    via by Frederic Simon,
  • TRIGGER_STATE = ERROR
    via by Unknown author,
  • Hi, we are observing deadlock errors during ArchiveIndexerImpl runs. This seems to happen irregulary, but it might have todo with size and/or increased load. Most failing artifacts are large (hundreds of MBs), however, some are much smaller (e.g. 4MB). At the same time the laod avarage on the machine was around 8. The result of this error is that searching for file names that are contained in the indexed archive does not yield the artifact among the search results. The error does not seem to be persistent as previous versions (which are around the same time) were indexed correctly. Here is the stacktrace: {quote} 2014-09-09 16:34:42,529 [art-exec-1828382] [INFO ] (o.a.s.a.ArchiveIndexerImpl:128) - Indexing archive: path/to/artifact/1.0.97-SNAPSHOT/artifact-1.0.97-20140909.142803-6.war .... 2014-09-09 16:34:55,919 [art-exec-1828382] [ERROR] (o.a.s.a.ArchiveIndexerImpl:178) - Exception indexing path/to/artifact/1.0.97-SNAPSHOT/artifact-1.0.97-20140909.142803-6.war org.artifactory.storage.StorageException: Failed to insert archive entries: Deadlock found when trying to get lock; try restarting transaction at org.artifactory.storage.db.fs.service.ArchiveEntriesServiceImpl.addArchiveEntries(ArchiveEntriesServiceImpl.java:116) ~[artifactory-storage-db-3.0.4.jar:na] at org.artifactory.search.archive.ArchiveIndexerImpl.index(ArchiveIndexerImpl.java:143) [artifactory-core-3.0.4.jar:na] at sun.reflect.GeneratedMethodAccessor234.invoke(Unknown Source) ~[na:na] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.7.0_05] at java.lang.reflect.Method.invoke(Method.java:601) ~[na:1.7.0_05] at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) [spring-aop-3.2.0.RELEASE.jar:3.2.0.RELEASE] at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183) [spring-aop-3.2.0.RELEASE.jar:3.2.0.RELEASE] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150) [spring-aop-3.2.0.RELEASE.jar:3.2.0.RELEASE] at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:110) ~[spring-tx-3.2.0.RELEASE.jar:3.2.0.RELEASE] at org.artifactory.storage.fs.lock.aop.LockingAdvice.invoke(LockingAdvice.java:64) ~[artifactory-storage-common-3.0.4.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) [spring-aop-3.2.0.RELEASE.jar:3.2.0.RELEASE] at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) ~[spring-aop-3.2.0.RELEASE.jar:3.2.0.RELEASE] at $Proxy45.index(Unknown Source) ~[na:na] at org.artifactory.search.archive.ArchiveIndexerImpl.triggerQueueIndexing(ArchiveIndexerImpl.java:173) [artifactory-core-3.0.4.jar:na] at org.artifactory.search.archive.ArchiveIndexerImpl.asyncIndex(ArchiveIndexerImpl.java:161) [artifactory-core-3.0.4.jar:na] at sun.reflect.GeneratedMethodAccessor233.invoke(Unknown Source) ~[na:na] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.7.0_05] at java.lang.reflect.Method.invoke(Method.java:601) ~[na:1.7.0_05] at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) [spring-aop-3.2.0.RELEASE.jar:3.2.0.RELEASE] at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183) [spring-aop-3.2.0.RELEASE.jar:3.2.0.RELEASE] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150) [spring-aop-3.2.0.RELEASE.jar:3.2.0.RELEASE] at org.artifactory.schedule.aop.AsyncAdvice$TraceableMethodInvocation.proceed(AsyncAdvice.java:337) [artifactory-core-3.0.4.jar:na] at org.artifactory.schedule.aop.AsyncAdvice.doInvoke(AsyncAdvice.java:206) [artifactory-core-3.0.4.jar:na] at org.artifactory.schedule.aop.CompoundInvocation.proceed(CompoundInvocation.java:56) [artifactory-core-3.0.4.jar:na] at org.artifactory.schedule.aop.AsyncAdvice.doInvoke(AsyncAdvice.java:182) [artifactory-core-3.0.4.jar:na] at org.artifactory.schedule.aop.AsyncAdvice$1.call(AsyncAdvice.java:143) [artifactory-core-3.0.4.jar:na] at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) [na:1.7.0_05] at java.util.concurrent.FutureTask.run(FutureTask.java:166) [na:1.7.0_05] at org.artifactory.schedule.ArtifactoryConcurrentExecutor$RunnableWrapper.run(ArtifactoryConcurrentExecutor.java:91) [artifactory-storage-common-3.0.4.jar:na] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) [na:1.7.0_05] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) [na:1.7.0_05] at java.lang.Thread.run(Thread.java:722) [na:1.7.0_05] Caused by: com.mysql.jdbc.exceptions.jdbc4.MySQLTransactionRollbackException: Deadlock found when trying to get lock; try restarting transaction at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) ~[na:1.7.0_05] at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57) ~[na:1.7.0_05] at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) ~[na:1.7.0_05] at java.lang.reflect.Constructor.newInstance(Constructor.java:525) ~[na:1.7.0_05] at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.Util.getInstance(Util.java:386) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1065) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4120) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4052) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2503) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2664) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2794) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2155) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2458) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2375) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2359) ~[mysql-connector-java-5.1.23-bin.jar:na] at org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:105) ~[commons-dbcp-1.4.jar:1.4] at org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:105) ~[commons-dbcp-1.4.jar:1.4] at org.artifactory.storage.db.util.JdbcHelper.executeUpdate(JdbcHelper.java:126) ~[artifactory-storage-db-3.0.4.jar:na] at org.artifactory.storage.db.fs.dao.ArchiveEntriesDao.createIndexedArchivesEntries(ArchiveEntriesDao.java:153) ~[artifactory-storage-db-3.0.4.jar:na] at org.artifactory.storage.db.fs.service.ArchiveEntriesServiceImpl.addArchiveEntries(ArchiveEntriesServiceImpl.java:112) ~[artifactory-storage-db-3.0.4.jar:na] ... 31 common frames omitted {quote} We are running version "Artifactory Pro Power Pack 3.0.4 (rev. 30058)" Best regards, Igor Lankin
    via by Igor Lankin,
  • Hi, we are observing deadlock errors during ArchiveIndexerImpl runs. This seems to happen irregulary, but it might have todo with size and/or increased load. Most failing artifacts are large (hundreds of MBs), however, some are much smaller (e.g. 4MB). At the same time the laod avarage on the machine was around 8. The result of this error is that searching for file names that are contained in the indexed archive does not yield the artifact among the search results. The error does not seem to be persistent as previous versions (which are around the same time) were indexed correctly. Here is the stacktrace: {quote} 2014-09-09 16:34:42,529 [art-exec-1828382] [INFO ] (o.a.s.a.ArchiveIndexerImpl:128) - Indexing archive: path/to/artifact/1.0.97-SNAPSHOT/artifact-1.0.97-20140909.142803-6.war .... 2014-09-09 16:34:55,919 [art-exec-1828382] [ERROR] (o.a.s.a.ArchiveIndexerImpl:178) - Exception indexing path/to/artifact/1.0.97-SNAPSHOT/artifact-1.0.97-20140909.142803-6.war org.artifactory.storage.StorageException: Failed to insert archive entries: Deadlock found when trying to get lock; try restarting transaction at org.artifactory.storage.db.fs.service.ArchiveEntriesServiceImpl.addArchiveEntries(ArchiveEntriesServiceImpl.java:116) ~[artifactory-storage-db-3.0.4.jar:na] at org.artifactory.search.archive.ArchiveIndexerImpl.index(ArchiveIndexerImpl.java:143) [artifactory-core-3.0.4.jar:na] at sun.reflect.GeneratedMethodAccessor234.invoke(Unknown Source) ~[na:na] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.7.0_05] at java.lang.reflect.Method.invoke(Method.java:601) ~[na:1.7.0_05] at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) [spring-aop-3.2.0.RELEASE.jar:3.2.0.RELEASE] at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183) [spring-aop-3.2.0.RELEASE.jar:3.2.0.RELEASE] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150) [spring-aop-3.2.0.RELEASE.jar:3.2.0.RELEASE] at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:110) ~[spring-tx-3.2.0.RELEASE.jar:3.2.0.RELEASE] at org.artifactory.storage.fs.lock.aop.LockingAdvice.invoke(LockingAdvice.java:64) ~[artifactory-storage-common-3.0.4.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) [spring-aop-3.2.0.RELEASE.jar:3.2.0.RELEASE] at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) ~[spring-aop-3.2.0.RELEASE.jar:3.2.0.RELEASE] at $Proxy45.index(Unknown Source) ~[na:na] at org.artifactory.search.archive.ArchiveIndexerImpl.triggerQueueIndexing(ArchiveIndexerImpl.java:173) [artifactory-core-3.0.4.jar:na] at org.artifactory.search.archive.ArchiveIndexerImpl.asyncIndex(ArchiveIndexerImpl.java:161) [artifactory-core-3.0.4.jar:na] at sun.reflect.GeneratedMethodAccessor233.invoke(Unknown Source) ~[na:na] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.7.0_05] at java.lang.reflect.Method.invoke(Method.java:601) ~[na:1.7.0_05] at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) [spring-aop-3.2.0.RELEASE.jar:3.2.0.RELEASE] at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183) [spring-aop-3.2.0.RELEASE.jar:3.2.0.RELEASE] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150) [spring-aop-3.2.0.RELEASE.jar:3.2.0.RELEASE] at org.artifactory.schedule.aop.AsyncAdvice$TraceableMethodInvocation.proceed(AsyncAdvice.java:337) [artifactory-core-3.0.4.jar:na] at org.artifactory.schedule.aop.AsyncAdvice.doInvoke(AsyncAdvice.java:206) [artifactory-core-3.0.4.jar:na] at org.artifactory.schedule.aop.CompoundInvocation.proceed(CompoundInvocation.java:56) [artifactory-core-3.0.4.jar:na] at org.artifactory.schedule.aop.AsyncAdvice.doInvoke(AsyncAdvice.java:182) [artifactory-core-3.0.4.jar:na] at org.artifactory.schedule.aop.AsyncAdvice$1.call(AsyncAdvice.java:143) [artifactory-core-3.0.4.jar:na] at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) [na:1.7.0_05] at java.util.concurrent.FutureTask.run(FutureTask.java:166) [na:1.7.0_05] at org.artifactory.schedule.ArtifactoryConcurrentExecutor$RunnableWrapper.run(ArtifactoryConcurrentExecutor.java:91) [artifactory-storage-common-3.0.4.jar:na] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) [na:1.7.0_05] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) [na:1.7.0_05] at java.lang.Thread.run(Thread.java:722) [na:1.7.0_05] Caused by: com.mysql.jdbc.exceptions.jdbc4.MySQLTransactionRollbackException: Deadlock found when trying to get lock; try restarting transaction at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) ~[na:1.7.0_05] at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57) ~[na:1.7.0_05] at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) ~[na:1.7.0_05] at java.lang.reflect.Constructor.newInstance(Constructor.java:525) ~[na:1.7.0_05] at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.Util.getInstance(Util.java:386) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1065) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4120) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4052) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2503) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2664) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2794) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2155) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2458) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2375) ~[mysql-connector-java-5.1.23-bin.jar:na] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2359) ~[mysql-connector-java-5.1.23-bin.jar:na] at org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:105) ~[commons-dbcp-1.4.jar:1.4] at org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:105) ~[commons-dbcp-1.4.jar:1.4] at org.artifactory.storage.db.util.JdbcHelper.executeUpdate(JdbcHelper.java:126) ~[artifactory-storage-db-3.0.4.jar:na] at org.artifactory.storage.db.fs.dao.ArchiveEntriesDao.createIndexedArchivesEntries(ArchiveEntriesDao.java:153) ~[artifactory-storage-db-3.0.4.jar:na] at org.artifactory.storage.db.fs.service.ArchiveEntriesServiceImpl.addArchiveEntries(ArchiveEntriesServiceImpl.java:112) ~[artifactory-storage-db-3.0.4.jar:na] ... 31 common frames omitted {quote} We are running version "Artifactory Pro Power Pack 3.0.4 (rev. 30058)" Best regards, Igor Lankin
    via by Igor Lankin,
    • exceptionorg.artifactory.storage.fs.VfsException: Failed to delete stats from node id 793346 at org.artifactory.storage.db.fs.service.StatsServiceImpl.deleteStats(StatsServiceImpl.java:130) at sun.reflect.GeneratedMethodAccessor212.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy48.deleteStats(Unknown Source) at org.artifactory.storage.db.fs.model.DbMutableFile.doDeleteInternal(DbMutableFile.java:173) at org.artifactory.storage.db.fs.model.DbMutableItem.save(DbMutableItem.java:209) at org.artifactory.storage.fs.lock.SessionLockEntry.save(SessionLockEntry.java:71) at org.artifactory.storage.db.fs.session.SqlStorageSession.save(SqlStorageSession.java:66) at org.artifactory.storage.db.spring.SessionSynchronization.beforeCommit(SessionSynchronization.java:74) at org.springframework.transaction.support.TransactionSynchronizationUtils.triggerBeforeCommit(TransactionSynchronizationUtils.java:95) at org.springframework.transaction.support.AbstractPlatformTransactionManager.triggerBeforeCommit(AbstractPlatformTransactionManager.java:924) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:737) at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:723) at org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:392) at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:120) at org.artifactory.storage.fs.lock.aop.LockingAdvice.invoke(LockingAdvice.java:64) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) at com.sun.proxy.$Proxy35.undeploy(Unknown Source) at org.artifactory.repo.webdav.WebdavServiceImpl.deleteItem(WebdavServiceImpl.java:276) at org.artifactory.repo.webdav.WebdavServiceImpl.handleDelete(WebdavServiceImpl.java:269) at sun.reflect.GeneratedMethodAccessor219.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy73.handleDelete(Unknown Source) at org.artifactory.webapp.servlet.RepoFilter.doWebDavMethod(RepoFilter.java:185) at org.artifactory.webapp.servlet.RepoFilter.execute(RepoFilter.java:150) at org.artifactory.webapp.servlet.RepoFilter.doFilter(RepoFilter.java:85) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:243) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:210) at org.artifactory.webapp.servlet.AccessFilter.useAuthentication(AccessFilter.java:272) at org.artifactory.webapp.servlet.AccessFilter.authenticateAndExecute(AccessFilter.java:193) at org.artifactory.webapp.servlet.AccessFilter.doFilterInternal(AccessFilter.java:175) at org.artifactory.webapp.servlet.AccessFilter.doFilter(AccessFilter.java:143) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:243) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:210) at org.artifactory.webapp.servlet.RequestFilter.doFilter(RequestFilter.java:57) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:243) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:210) at org.artifactory.webapp.servlet.ArtifactoryFilter.doFilter(ArtifactoryFilter.java:72) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:243) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:210) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:222) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:123) at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:472) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:171) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:99) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:118) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408) at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1009) at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:589) at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:310) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:722) Caused by: com.mysql.jdbc.exceptions.jdbc4.MySQLTransactionRollbackException: Deadlock found when trying to get lock; try restarting transaction at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) at java.lang.reflect.Constructor.newInstance(Constructor.java:525) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.Util.getInstance(Util.java:386) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1065) at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4096) at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4028) at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2490) at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2651) at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2734) at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2155) at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2458) at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2375) at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2359) at org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:105) at org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:105) at org.artifactory.storage.db.util.JdbcHelper.executeUpdate(JdbcHelper.java:125) at org.artifactory.storage.db.fs.dao.StatsDao.deleteStats(StatsDao.java:77)

    Users with the same issue

    Unknown visitor
    Unknown visitor1 times, last one,
    Unknown visitor
    Unknown visitor1 times, last one,
    Unknown visitor
    Unknown visitor1 times, last one,
    Unknown visitor
    Unknown visitor1 times, last one,
    ivanszkypeterivanszkypeter
    20 times, last one,
    3 more bugmates