java.lang.RuntimeException: Failed to execute JcrCallback.

JFrog JIRA | Peter Lynch | 10 years ago
tip
Do you know that we can give you better hits? Get more relevant results from Samebug’s stack trace search.
  1. 0

    Saw this in the logs when a backup was being performed: 2007-05-18 12:01:26,398 [DEBUG] core.observation.ObservationDispatcher - notifying 2 synchronous listeners. 2007-05-18 12:01:26,398 [DEBUG] jackrabbit.core.SearchManager - onEvent: indexing started 2007-05-18 12:01:26,457 [DEBUG] jackrabbit.core.SearchManager - onEvent: indexing finished in 59 ms. 2007-05-18 12:01:26,457 [DEBUG] core.observation.ObservationDispatcher - got EventStateCollection 2007-05-18 12:01:26,457 [DEBUG] core.observation.ObservationDispatcher - event delivery to 1 consumers started... 2007-05-18 12:01:26,458 [DEBUG] core.observation.ObservationDispatcher - event delivery finished. 2007-05-18 12:01:26,458 [DEBUG] artifactory.jcr.NodeLock - Locked node '/prefix-repo-dev-release/com/example/common/spring' with session org.apache.jackrabbit.core.XASessionImpl@44b53c /prefix-repo-dev-release/com/example/common/spring /prefix-repo-dev-release/com/example/common/spring/jcr:lockIsDeep=false 2007-05-18 12:01:26,458 [DEBUG] jackrabbit.core.ItemManager - created item c460c40c-356c-4d39-955c-a5b5f2eec9ec/{http://artifactory.jfrog.org/1.0}modifiedBy 2007-05-18 12:01:26,458 [DEBUG] jackrabbit.core.ItemManager - caching item c460c40c-356c-4d39-955c-a5b5f2eec9ec/{http://artifactory.jfrog.org/1.0}modifiedBy /prefix-repo-dev-release/com/example/common/spring/artifactory:repoKey=prefix-repo-dev-release 2007-05-18 12:01:26,458 [INFO ] core.state.CacheManager - resizeAll size=8 2007-05-18 12:01:26,462 [DEBUG] core.state.CacheManager - org.apache.jackrabbit.core.state.MLRUItemStateCache@17753a8 now:131072 used:52104 access:20 new:131072 2007-05-18 12:01:26,462 [DEBUG] core.state.CacheManager - org.apache.jackrabbit.core.state.MLRUItemStateCache@648938 now:7995392 used:4211304 access:123 new:4194304 2007-05-18 12:01:26,462 [DEBUG] core.state.CacheManager - org.apache.jackrabbit.core.state.MLRUItemStateCache@1a3551c now:131072 used:664 access:0 new:131072 2007-05-18 12:01:26,462 [DEBUG] core.state.CacheManager - org.apache.jackrabbit.core.state.MLRUItemStateCache@8aaff4 now:131072 used:664 access:0 new:131072 2007-05-18 12:01:26,462 [DEBUG] core.state.CacheManager - org.apache.jackrabbit.core.state.MLRUItemStateCache@e58bea now:131072 used:664 access:0 new:131072 2007-05-18 12:01:26,462 [DEBUG] core.state.CacheManager - org.apache.jackrabbit.core.state.MLRUItemStateCache@93837b now:131072 used:53658 access:5 new:131072 2007-05-18 12:01:26,462 [DEBUG] core.state.CacheManager - org.apache.jackrabbit.core.state.MLRUItemStateCache@281a86 now:7995392 used:1995556 access:127 new:1995556 2007-05-18 12:01:26,462 [DEBUG] core.state.CacheManager - org.apache.jackrabbit.core.state.MLRUItemStateCache@17082d8 now:131072 used:664 access:0 new:131072 2007-05-18 12:01:26,462 [DEBUG] jackrabbit.core.ItemManager - created item c460c40c-356c-4d39-955c-a5b5f2eec9ec/{http://www.jcp.org/jcr/1.0}created 2007-05-18 12:01:26,462 [DEBUG] jackrabbit.core.ItemManager - caching item c460c40c-356c-4d39-955c-a5b5f2eec9ec/{http://www.jcp.org/jcr/1.0}created /prefix-repo-dev-release/com/example/common/spring/artifactory:modifiedBy=admin /prefix-repo-dev-release/com/example/common/spring/jcr:created=2007-05-17T15:05:59.273-04:00 2007-05-18 12:01:26,463 [DEBUG] jackrabbit.core.ItemManager - created item c460c40c-356c-4d39-955c-a5b5f2eec9ec/{http://www.jcp.org/jcr/1.0}primaryType 2007-05-18 12:01:26,463 [DEBUG] jackrabbit.core.ItemManager - caching item c460c40c-356c-4d39-955c-a5b5f2eec9ec/{http://www.jcp.org/jcr/1.0}primaryType /prefix-repo-dev-release/com/example/common/spring/jcr:lockOwner=superuser /prefix-repo-dev-release/com/example/common/spring/jcr:primaryType=artifactory:folder 2007-05-18 12:01:26,464 [DEBUG] jackrabbit.core.ItemManager - created item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://www.jcp.org/jcr/1.0}lockOwner 2007-05-18 12:01:26,464 [DEBUG] jackrabbit.core.ItemManager - caching item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://www.jcp.org/jcr/1.0}lockOwner 2007-05-18 12:01:26,465 [DEBUG] jackrabbit.core.ItemManager - created item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://www.jcp.org/jcr/1.0}lockIsDeep 2007-05-18 12:01:26,465 [DEBUG] jackrabbit.core.ItemManager - caching item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://www.jcp.org/jcr/1.0}lockIsDeep 2007-05-18 12:01:26,487 [DEBUG] core.state.SharedItemStateManager - persisting change log {#addedStates=2, #modifiedStates=1, #deletedStates=0, #modifiedRefs=0} took 22ms 2007-05-18 12:01:26,488 [DEBUG] core.observation.ObservationDispatcher - notifying 2 synchronous listeners. 2007-05-18 12:01:26,488 [DEBUG] jackrabbit.core.SearchManager - onEvent: indexing started 2007-05-18 12:01:26,530 [DEBUG] jackrabbit.core.SearchManager - onEvent: indexing finished in 42 ms. 2007-05-18 12:01:26,530 [DEBUG] core.observation.ObservationDispatcher - got EventStateCollection 2007-05-18 12:01:26,530 [DEBUG] core.observation.ObservationDispatcher - event delivery to 1 consumers started... 2007-05-18 12:01:26,530 [DEBUG] core.observation.ObservationDispatcher - event delivery finished. 2007-05-18 12:01:26,530 [DEBUG] artifactory.jcr.NodeLock - Locked node '/prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web' with session org.apache.jackrabbit.core.XASessionImpl@44b53c /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/jcr:lockIsDeep=false 2007-05-18 12:01:26,531 [DEBUG] jackrabbit.core.ItemManager - created item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://www.jcp.org/jcr/1.0}created 2007-05-18 12:01:26,531 [DEBUG] jackrabbit.core.ItemManager - caching item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://www.jcp.org/jcr/1.0}created /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/artifactory:repoKey=prefix-repo-dev-release 2007-05-18 12:01:26,531 [DEBUG] jackrabbit.core.ItemManager - created item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://artifactory.jfrog.org/1.0}modifiedBy 2007-05-18 12:01:26,531 [DEBUG] jackrabbit.core.ItemManager - caching item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://artifactory.jfrog.org/1.0}modifiedBy /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/jcr:created=2007-05-17T15:05:59.275-04:00 /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/artifactory:modifiedBy=admin 2007-05-18 12:01:26,532 [DEBUG] jackrabbit.core.ItemManager - created item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://www.jcp.org/jcr/1.0}primaryType 2007-05-18 12:01:26,532 [DEBUG] jackrabbit.core.ItemManager - caching item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://www.jcp.org/jcr/1.0}primaryType /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/jcr:lockOwner=superuser /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/jcr:primaryType=artifactory:folder 2007-05-18 12:01:26,539 [DEBUG] jackrabbit.core.ItemManager - created item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://www.jcp.org/jcr/1.0}lockOwner 2007-05-18 12:01:26,539 [DEBUG] jackrabbit.core.ItemManager - caching item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://www.jcp.org/jcr/1.0}lockOwner 2007-05-18 12:01:26,539 [DEBUG] jackrabbit.core.ItemManager - created item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://www.jcp.org/jcr/1.0}lockIsDeep 2007-05-18 12:01:26,540 [DEBUG] jackrabbit.core.ItemManager - caching item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://www.jcp.org/jcr/1.0}lockIsDeep 2007-05-18 12:01:26,559 [DEBUG] core.state.SharedItemStateManager - persisting change log {#addedStates=2, #modifiedStates=1, #deletedStates=0, #modifiedRefs=0} took 19ms 2007-05-18 12:01:26,560 [DEBUG] core.observation.ObservationDispatcher - notifying 2 synchronous listeners. 2007-05-18 12:01:26,560 [DEBUG] jackrabbit.core.SearchManager - onEvent: indexing started 2007-05-18 12:01:26,768 [DEBUG] jackrabbit.core.SearchManager - onEvent: indexing finished in 208 ms. 2007-05-18 12:01:26,768 [DEBUG] core.observation.ObservationDispatcher - got EventStateCollection 2007-05-18 12:01:26,768 [DEBUG] core.observation.ObservationDispatcher - event delivery to 1 consumers started... 2007-05-18 12:01:26,768 [DEBUG] core.observation.ObservationDispatcher - event delivery finished. 2007-05-18 12:01:26,768 [DEBUG] artifactory.jcr.NodeLock - Locked node '/prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2' with session org.apache.jackrabbit.core.XASessionImpl@44b53c /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2 /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/jcr:lockIsDeep=false 2007-05-18 12:01:26,769 [DEBUG] jackrabbit.core.ItemManager - created item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://www.jcp.org/jcr/1.0}created 2007-05-18 12:01:26,769 [DEBUG] jackrabbit.core.ItemManager - caching item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://www.jcp.org/jcr/1.0}created /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/artifactory:repoKey=prefix-repo-dev-release 2007-05-18 12:01:26,769 [DEBUG] jackrabbit.core.ItemManager - created item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://artifactory.jfrog.org/1.0}modifiedBy 2007-05-18 12:01:26,769 [DEBUG] jackrabbit.core.ItemManager - caching item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://artifactory.jfrog.org/1.0}modifiedBy /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/jcr:created=2007-05-17T15:05:59.278-04:00 /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/artifactory:modifiedBy=admin 2007-05-18 12:01:26,769 [DEBUG] jackrabbit.core.ItemManager - created item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://www.jcp.org/jcr/1.0}primaryType 2007-05-18 12:01:26,769 [DEBUG] jackrabbit.core.ItemManager - caching item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://www.jcp.org/jcr/1.0}primaryType /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/jcr:lockOwner=superuser /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/jcr:primaryType=artifactory:folder 2007-05-18 12:01:26,776 [DEBUG] jackrabbit.core.ItemManager - created item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}lockOwner 2007-05-18 12:01:26,776 [DEBUG] jackrabbit.core.ItemManager - caching item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}lockOwner 2007-05-18 12:01:26,777 [DEBUG] jackrabbit.core.ItemManager - created item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}lockIsDeep 2007-05-18 12:01:26,777 [DEBUG] jackrabbit.core.ItemManager - caching item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}lockIsDeep 2007-05-18 12:01:26,793 [DEBUG] core.state.SharedItemStateManager - persisting change log {#addedStates=2, #modifiedStates=1, #deletedStates=0, #modifiedRefs=0} took 15ms 2007-05-18 12:01:26,793 [DEBUG] core.observation.ObservationDispatcher - notifying 2 synchronous listeners. 2007-05-18 12:01:26,793 [DEBUG] jackrabbit.core.SearchManager - onEvent: indexing started 2007-05-18 12:01:27,024 [DEBUG] jackrabbit.core.SearchManager - onEvent: indexing finished in 230 ms. 2007-05-18 12:01:27,024 [DEBUG] core.observation.ObservationDispatcher - got EventStateCollection 2007-05-18 12:01:27,024 [DEBUG] core.observation.ObservationDispatcher - event delivery to 1 consumers started... 2007-05-18 12:01:27,024 [DEBUG] core.observation.ObservationDispatcher - event delivery finished. 2007-05-18 12:01:27,025 [DEBUG] artifactory.jcr.NodeLock - Locked node '/prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar' with session org.apache.jackrabbit.core.XASessionImpl@44b53c /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar 2007-05-18 12:01:27,025 [DEBUG] jackrabbit.core.ItemManager - created item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}uuid 2007-05-18 12:01:27,025 [DEBUG] jackrabbit.core.ItemManager - caching item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}uuid /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:uuid=b19c1ccb-eadf-405a-8c88-bc539101c2a8 /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:lockIsDeep=false 2007-05-18 12:01:27,025 [DEBUG] jackrabbit.core.ItemManager - created item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://artifactory.jfrog.org/1.0}modifiedBy 2007-05-18 12:01:27,025 [DEBUG] jackrabbit.core.ItemManager - caching item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://artifactory.jfrog.org/1.0}modifiedBy /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/artifactory:repoKey=prefix-repo-dev-release 2007-05-18 12:01:27,026 [DEBUG] jackrabbit.core.ItemManager - created item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}created 2007-05-18 12:01:27,026 [DEBUG] jackrabbit.core.ItemManager - caching item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}created /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/artifactory:modifiedBy=admin 2007-05-18 12:01:27,026 [DEBUG] jackrabbit.core.ItemManager - created item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://artifactory.jfrog.org/1.0}lastUpdated 2007-05-18 12:01:27,026 [DEBUG] jackrabbit.core.ItemManager - caching item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://artifactory.jfrog.org/1.0}lastUpdated /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:created=2007-05-17T15:05:59.282-04:00 /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/artifactory:lastUpdated=2007-05-17T15:05:59.283-04:00 2007-05-18 12:01:27,026 [DEBUG] jackrabbit.core.ItemManager - created item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}primaryType 2007-05-18 12:01:27,026 [DEBUG] jackrabbit.core.ItemManager - caching item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}primaryType /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:lockOwner=superuser 2007-05-18 12:01:27,027 [DEBUG] jackrabbit.core.ItemManager - created item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://artifactory.jfrog.org/1.0}name 2007-05-18 12:01:27,027 [DEBUG] jackrabbit.core.ItemManager - caching item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://artifactory.jfrog.org/1.0}name /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:primaryType=artifactory:file /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/artifactory:name=prefix-common-spring-web-1.0.2.jar 2007-05-18 12:01:27,027 [DEBUG] jackrabbit.core.NodeImpl - Unable to perform locking operation on non-lockable node: /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:content 2007-05-18 12:01:27,028 [DEBUG] artifactory.jcr.NodeLock - Retrying lock on '/prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:content' in 1000ms. 2007-05-18 12:01:28,031 [DEBUG] jackrabbit.core.NodeImpl - Unable to perform locking operation on non-lockable node: /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:content 2007-05-18 12:01:28,031 [DEBUG] artifactory.jcr.NodeLock - Retrying lock on '/prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:content' in 1000ms. 2007-05-18 12:01:29,041 [DEBUG] jackrabbit.core.NodeImpl - Unable to perform locking operation on non-lockable node: /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:content 2007-05-18 12:01:29,184 [ERROR] quartz.core.JobRunShell - Job DEFAULT.backupJobDetail threw an unhandled Exception: java.lang.RuntimeException: Failed to execute JcrCallback. at org.artifactory.jcr.JcrHelper.doInSession(JcrHelper.java:186) at org.artifactory.repo.JcrRepo.exportToDir(JcrRepo.java:279) at org.artifactory.repo.CentralConfig.backupRepos(CentralConfig.java:168) at org.artifactory.repo.CentralConfig.backupRepos(CentralConfig.java:159) at org.artifactory.backup.BackupJob.execute(BackupJob.java:24) at org.quartz.core.JobRunShell.run(JobRunShell.java:202) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:529) Caused by: java.lang.RuntimeException: Failed to acquire node lock on '/prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:content' after 3 retries. at org.artifactory.jcr.NodeLock.lock(NodeLock.java:79) at org.artifactory.jcr.NodeLock.lock(NodeLock.java:39) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:428) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.repo.JcrRepo$6.doInJcr(JcrRepo.java:285) at org.artifactory.repo.JcrRepo$6.doInJcr(JcrRepo.java:280) at org.artifactory.jcr.JcrHelper.doInSession(JcrHelper.java:182) ... 6 more 2007-05-18 12:01:29,234 [ERROR] quartz.core.ErrorLogger - Job (DEFAULT.backupJobDetail threw an exception. org.quartz.SchedulerException: Job threw an unhandled exception. [See nested exception: java.lang.RuntimeException: Failed to execute JcrCallback.] at org.quartz.core.JobRunShell.run(JobRunShell.java:213) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:529) Caused by: java.lang.RuntimeException: Failed to execute JcrCallback. at org.artifactory.jcr.JcrHelper.doInSession(JcrHelper.java:186) at org.artifactory.repo.JcrRepo.exportToDir(JcrRepo.java:279) at org.artifactory.repo.CentralConfig.backupRepos(CentralConfig.java:168) at org.artifactory.repo.CentralConfig.backupRepos(CentralConfig.java:159) at org.artifactory.backup.BackupJob.execute(BackupJob.java:24) at org.quartz.core.JobRunShell.run(JobRunShell.java:202) ... 1 more Caused by: java.lang.RuntimeException: Failed to acquire node lock on '/prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:content' after 3 retries. at org.artifactory.jcr.NodeLock.lock(NodeLock.java:79) at org.artifactory.jcr.NodeLock.lock(NodeLock.java:39) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:428) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.repo.JcrRepo$6.doInJcr(JcrRepo.java:285) at org.artifactory.repo.JcrRepo$6.doInJcr(JcrRepo.java:280) at org.artifactory.jcr.JcrHelper.doInSession(JcrHelper.java:182) ... 6 more 2007-05-18 12:01:30,050 [DEBUG] query.lucene.MultiIndex - Flushing index after being idle for 3026 ms. 2007-05-18 12:01:30,069 [DEBUG] query.lucene.AbstractIndex - closing IndexReader. 2007-05-18 12:01:30,079 [DEBUG] query.lucene.AbstractIndex - committing IndexWriter. 2007-05-18 12:01:30,081 [DEBUG] query.lucene.AbstractIndex - merging segments _0 (1 docs) _1 (1 docs) _2 (1 docs) _3 (1 docs) _4 (1 docs) _5 (1 docs) _6 (1 docs) _7 (1 docs) into _8 (8 docs) 2007-05-18 12:01:30,094 [DEBUG] query.lucene.IndexMerger - index added: name=_gd, numDocs=8 2007-05-18 12:01:30,094 [DEBUG] query.lucene.IndexMerger - requesting merge for [_g4:311, _g5:311, _g6:5, _g7:311, _g8:311, _g9:311, _ga:311, _gb:311, _gc:311, _gd:8] 2007-05-18 12:01:30,094 [DEBUG] query.lucene.IndexMerger - merge queue now contains 1 tasks. 2007-05-18 12:01:30,094 [DEBUG] query.lucene.IndexMerger - accepted merge request 2007-05-18 12:01:30,094 [DEBUG] query.lucene.IndexMerger - create new index 2007-05-18 12:01:30,094 [DEBUG] query.lucene.MultiIndex - Committed in-memory index in 29ms. 2007-05-18 12:01:30,157 [DEBUG] query.lucene.IndexMerger - get index readers from MultiIndex 2007-05-18 12:01:30,714 [INFO ] query.lucene.IndexMerger - merged 1929 documents in 459 ms into _ge. 2007-05-18 12:01:30,714 [DEBUG] query.lucene.IndexMerger - replace indexes 2007-05-18 12:01:30,718 [DEBUG] query.lucene.AbstractIndex - closing IndexWriter. 2007-05-18 12:01:30,719 [DEBUG] query.lucene.IndexMerger - index added: name=_ge, numDocs=1929 2007-05-18 12:01:34,081 [DEBUG] query.lucene.MultiIndex - Flushing index after being idle for 3362 ms. 2007-05-18 12:03:49,103 [DEBUG] artifactory.maven.WagonManagerTempArtifactsCleaner - WagonManager temp artifacts cleaner deleted 0 file(s).

    JFrog JIRA | 10 years ago | Peter Lynch
    java.lang.RuntimeException: Failed to execute JcrCallback.
  2. 0

    Saw this in the logs when a backup was being performed: 2007-05-18 12:01:26,398 [DEBUG] core.observation.ObservationDispatcher - notifying 2 synchronous listeners. 2007-05-18 12:01:26,398 [DEBUG] jackrabbit.core.SearchManager - onEvent: indexing started 2007-05-18 12:01:26,457 [DEBUG] jackrabbit.core.SearchManager - onEvent: indexing finished in 59 ms. 2007-05-18 12:01:26,457 [DEBUG] core.observation.ObservationDispatcher - got EventStateCollection 2007-05-18 12:01:26,457 [DEBUG] core.observation.ObservationDispatcher - event delivery to 1 consumers started... 2007-05-18 12:01:26,458 [DEBUG] core.observation.ObservationDispatcher - event delivery finished. 2007-05-18 12:01:26,458 [DEBUG] artifactory.jcr.NodeLock - Locked node '/prefix-repo-dev-release/com/example/common/spring' with session org.apache.jackrabbit.core.XASessionImpl@44b53c /prefix-repo-dev-release/com/example/common/spring /prefix-repo-dev-release/com/example/common/spring/jcr:lockIsDeep=false 2007-05-18 12:01:26,458 [DEBUG] jackrabbit.core.ItemManager - created item c460c40c-356c-4d39-955c-a5b5f2eec9ec/{http://artifactory.jfrog.org/1.0}modifiedBy 2007-05-18 12:01:26,458 [DEBUG] jackrabbit.core.ItemManager - caching item c460c40c-356c-4d39-955c-a5b5f2eec9ec/{http://artifactory.jfrog.org/1.0}modifiedBy /prefix-repo-dev-release/com/example/common/spring/artifactory:repoKey=prefix-repo-dev-release 2007-05-18 12:01:26,458 [INFO ] core.state.CacheManager - resizeAll size=8 2007-05-18 12:01:26,462 [DEBUG] core.state.CacheManager - org.apache.jackrabbit.core.state.MLRUItemStateCache@17753a8 now:131072 used:52104 access:20 new:131072 2007-05-18 12:01:26,462 [DEBUG] core.state.CacheManager - org.apache.jackrabbit.core.state.MLRUItemStateCache@648938 now:7995392 used:4211304 access:123 new:4194304 2007-05-18 12:01:26,462 [DEBUG] core.state.CacheManager - org.apache.jackrabbit.core.state.MLRUItemStateCache@1a3551c now:131072 used:664 access:0 new:131072 2007-05-18 12:01:26,462 [DEBUG] core.state.CacheManager - org.apache.jackrabbit.core.state.MLRUItemStateCache@8aaff4 now:131072 used:664 access:0 new:131072 2007-05-18 12:01:26,462 [DEBUG] core.state.CacheManager - org.apache.jackrabbit.core.state.MLRUItemStateCache@e58bea now:131072 used:664 access:0 new:131072 2007-05-18 12:01:26,462 [DEBUG] core.state.CacheManager - org.apache.jackrabbit.core.state.MLRUItemStateCache@93837b now:131072 used:53658 access:5 new:131072 2007-05-18 12:01:26,462 [DEBUG] core.state.CacheManager - org.apache.jackrabbit.core.state.MLRUItemStateCache@281a86 now:7995392 used:1995556 access:127 new:1995556 2007-05-18 12:01:26,462 [DEBUG] core.state.CacheManager - org.apache.jackrabbit.core.state.MLRUItemStateCache@17082d8 now:131072 used:664 access:0 new:131072 2007-05-18 12:01:26,462 [DEBUG] jackrabbit.core.ItemManager - created item c460c40c-356c-4d39-955c-a5b5f2eec9ec/{http://www.jcp.org/jcr/1.0}created 2007-05-18 12:01:26,462 [DEBUG] jackrabbit.core.ItemManager - caching item c460c40c-356c-4d39-955c-a5b5f2eec9ec/{http://www.jcp.org/jcr/1.0}created /prefix-repo-dev-release/com/example/common/spring/artifactory:modifiedBy=admin /prefix-repo-dev-release/com/example/common/spring/jcr:created=2007-05-17T15:05:59.273-04:00 2007-05-18 12:01:26,463 [DEBUG] jackrabbit.core.ItemManager - created item c460c40c-356c-4d39-955c-a5b5f2eec9ec/{http://www.jcp.org/jcr/1.0}primaryType 2007-05-18 12:01:26,463 [DEBUG] jackrabbit.core.ItemManager - caching item c460c40c-356c-4d39-955c-a5b5f2eec9ec/{http://www.jcp.org/jcr/1.0}primaryType /prefix-repo-dev-release/com/example/common/spring/jcr:lockOwner=superuser /prefix-repo-dev-release/com/example/common/spring/jcr:primaryType=artifactory:folder 2007-05-18 12:01:26,464 [DEBUG] jackrabbit.core.ItemManager - created item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://www.jcp.org/jcr/1.0}lockOwner 2007-05-18 12:01:26,464 [DEBUG] jackrabbit.core.ItemManager - caching item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://www.jcp.org/jcr/1.0}lockOwner 2007-05-18 12:01:26,465 [DEBUG] jackrabbit.core.ItemManager - created item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://www.jcp.org/jcr/1.0}lockIsDeep 2007-05-18 12:01:26,465 [DEBUG] jackrabbit.core.ItemManager - caching item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://www.jcp.org/jcr/1.0}lockIsDeep 2007-05-18 12:01:26,487 [DEBUG] core.state.SharedItemStateManager - persisting change log {#addedStates=2, #modifiedStates=1, #deletedStates=0, #modifiedRefs=0} took 22ms 2007-05-18 12:01:26,488 [DEBUG] core.observation.ObservationDispatcher - notifying 2 synchronous listeners. 2007-05-18 12:01:26,488 [DEBUG] jackrabbit.core.SearchManager - onEvent: indexing started 2007-05-18 12:01:26,530 [DEBUG] jackrabbit.core.SearchManager - onEvent: indexing finished in 42 ms. 2007-05-18 12:01:26,530 [DEBUG] core.observation.ObservationDispatcher - got EventStateCollection 2007-05-18 12:01:26,530 [DEBUG] core.observation.ObservationDispatcher - event delivery to 1 consumers started... 2007-05-18 12:01:26,530 [DEBUG] core.observation.ObservationDispatcher - event delivery finished. 2007-05-18 12:01:26,530 [DEBUG] artifactory.jcr.NodeLock - Locked node '/prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web' with session org.apache.jackrabbit.core.XASessionImpl@44b53c /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/jcr:lockIsDeep=false 2007-05-18 12:01:26,531 [DEBUG] jackrabbit.core.ItemManager - created item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://www.jcp.org/jcr/1.0}created 2007-05-18 12:01:26,531 [DEBUG] jackrabbit.core.ItemManager - caching item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://www.jcp.org/jcr/1.0}created /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/artifactory:repoKey=prefix-repo-dev-release 2007-05-18 12:01:26,531 [DEBUG] jackrabbit.core.ItemManager - created item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://artifactory.jfrog.org/1.0}modifiedBy 2007-05-18 12:01:26,531 [DEBUG] jackrabbit.core.ItemManager - caching item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://artifactory.jfrog.org/1.0}modifiedBy /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/jcr:created=2007-05-17T15:05:59.275-04:00 /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/artifactory:modifiedBy=admin 2007-05-18 12:01:26,532 [DEBUG] jackrabbit.core.ItemManager - created item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://www.jcp.org/jcr/1.0}primaryType 2007-05-18 12:01:26,532 [DEBUG] jackrabbit.core.ItemManager - caching item f945b4c9-ee49-4c0a-a431-9ae45bf0df6a/{http://www.jcp.org/jcr/1.0}primaryType /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/jcr:lockOwner=superuser /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/jcr:primaryType=artifactory:folder 2007-05-18 12:01:26,539 [DEBUG] jackrabbit.core.ItemManager - created item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://www.jcp.org/jcr/1.0}lockOwner 2007-05-18 12:01:26,539 [DEBUG] jackrabbit.core.ItemManager - caching item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://www.jcp.org/jcr/1.0}lockOwner 2007-05-18 12:01:26,539 [DEBUG] jackrabbit.core.ItemManager - created item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://www.jcp.org/jcr/1.0}lockIsDeep 2007-05-18 12:01:26,540 [DEBUG] jackrabbit.core.ItemManager - caching item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://www.jcp.org/jcr/1.0}lockIsDeep 2007-05-18 12:01:26,559 [DEBUG] core.state.SharedItemStateManager - persisting change log {#addedStates=2, #modifiedStates=1, #deletedStates=0, #modifiedRefs=0} took 19ms 2007-05-18 12:01:26,560 [DEBUG] core.observation.ObservationDispatcher - notifying 2 synchronous listeners. 2007-05-18 12:01:26,560 [DEBUG] jackrabbit.core.SearchManager - onEvent: indexing started 2007-05-18 12:01:26,768 [DEBUG] jackrabbit.core.SearchManager - onEvent: indexing finished in 208 ms. 2007-05-18 12:01:26,768 [DEBUG] core.observation.ObservationDispatcher - got EventStateCollection 2007-05-18 12:01:26,768 [DEBUG] core.observation.ObservationDispatcher - event delivery to 1 consumers started... 2007-05-18 12:01:26,768 [DEBUG] core.observation.ObservationDispatcher - event delivery finished. 2007-05-18 12:01:26,768 [DEBUG] artifactory.jcr.NodeLock - Locked node '/prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2' with session org.apache.jackrabbit.core.XASessionImpl@44b53c /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2 /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/jcr:lockIsDeep=false 2007-05-18 12:01:26,769 [DEBUG] jackrabbit.core.ItemManager - created item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://www.jcp.org/jcr/1.0}created 2007-05-18 12:01:26,769 [DEBUG] jackrabbit.core.ItemManager - caching item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://www.jcp.org/jcr/1.0}created /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/artifactory:repoKey=prefix-repo-dev-release 2007-05-18 12:01:26,769 [DEBUG] jackrabbit.core.ItemManager - created item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://artifactory.jfrog.org/1.0}modifiedBy 2007-05-18 12:01:26,769 [DEBUG] jackrabbit.core.ItemManager - caching item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://artifactory.jfrog.org/1.0}modifiedBy /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/jcr:created=2007-05-17T15:05:59.278-04:00 /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/artifactory:modifiedBy=admin 2007-05-18 12:01:26,769 [DEBUG] jackrabbit.core.ItemManager - created item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://www.jcp.org/jcr/1.0}primaryType 2007-05-18 12:01:26,769 [DEBUG] jackrabbit.core.ItemManager - caching item 3193a27a-d48c-4a5f-8efc-47b57912c239/{http://www.jcp.org/jcr/1.0}primaryType /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/jcr:lockOwner=superuser /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/jcr:primaryType=artifactory:folder 2007-05-18 12:01:26,776 [DEBUG] jackrabbit.core.ItemManager - created item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}lockOwner 2007-05-18 12:01:26,776 [DEBUG] jackrabbit.core.ItemManager - caching item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}lockOwner 2007-05-18 12:01:26,777 [DEBUG] jackrabbit.core.ItemManager - created item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}lockIsDeep 2007-05-18 12:01:26,777 [DEBUG] jackrabbit.core.ItemManager - caching item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}lockIsDeep 2007-05-18 12:01:26,793 [DEBUG] core.state.SharedItemStateManager - persisting change log {#addedStates=2, #modifiedStates=1, #deletedStates=0, #modifiedRefs=0} took 15ms 2007-05-18 12:01:26,793 [DEBUG] core.observation.ObservationDispatcher - notifying 2 synchronous listeners. 2007-05-18 12:01:26,793 [DEBUG] jackrabbit.core.SearchManager - onEvent: indexing started 2007-05-18 12:01:27,024 [DEBUG] jackrabbit.core.SearchManager - onEvent: indexing finished in 230 ms. 2007-05-18 12:01:27,024 [DEBUG] core.observation.ObservationDispatcher - got EventStateCollection 2007-05-18 12:01:27,024 [DEBUG] core.observation.ObservationDispatcher - event delivery to 1 consumers started... 2007-05-18 12:01:27,024 [DEBUG] core.observation.ObservationDispatcher - event delivery finished. 2007-05-18 12:01:27,025 [DEBUG] artifactory.jcr.NodeLock - Locked node '/prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar' with session org.apache.jackrabbit.core.XASessionImpl@44b53c /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar 2007-05-18 12:01:27,025 [DEBUG] jackrabbit.core.ItemManager - created item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}uuid 2007-05-18 12:01:27,025 [DEBUG] jackrabbit.core.ItemManager - caching item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}uuid /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:uuid=b19c1ccb-eadf-405a-8c88-bc539101c2a8 /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:lockIsDeep=false 2007-05-18 12:01:27,025 [DEBUG] jackrabbit.core.ItemManager - created item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://artifactory.jfrog.org/1.0}modifiedBy 2007-05-18 12:01:27,025 [DEBUG] jackrabbit.core.ItemManager - caching item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://artifactory.jfrog.org/1.0}modifiedBy /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/artifactory:repoKey=prefix-repo-dev-release 2007-05-18 12:01:27,026 [DEBUG] jackrabbit.core.ItemManager - created item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}created 2007-05-18 12:01:27,026 [DEBUG] jackrabbit.core.ItemManager - caching item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}created /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/artifactory:modifiedBy=admin 2007-05-18 12:01:27,026 [DEBUG] jackrabbit.core.ItemManager - created item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://artifactory.jfrog.org/1.0}lastUpdated 2007-05-18 12:01:27,026 [DEBUG] jackrabbit.core.ItemManager - caching item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://artifactory.jfrog.org/1.0}lastUpdated /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:created=2007-05-17T15:05:59.282-04:00 /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/artifactory:lastUpdated=2007-05-17T15:05:59.283-04:00 2007-05-18 12:01:27,026 [DEBUG] jackrabbit.core.ItemManager - created item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}primaryType 2007-05-18 12:01:27,026 [DEBUG] jackrabbit.core.ItemManager - caching item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://www.jcp.org/jcr/1.0}primaryType /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:lockOwner=superuser 2007-05-18 12:01:27,027 [DEBUG] jackrabbit.core.ItemManager - created item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://artifactory.jfrog.org/1.0}name 2007-05-18 12:01:27,027 [DEBUG] jackrabbit.core.ItemManager - caching item b19c1ccb-eadf-405a-8c88-bc539101c2a8/{http://artifactory.jfrog.org/1.0}name /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:primaryType=artifactory:file /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/artifactory:name=prefix-common-spring-web-1.0.2.jar 2007-05-18 12:01:27,027 [DEBUG] jackrabbit.core.NodeImpl - Unable to perform locking operation on non-lockable node: /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:content 2007-05-18 12:01:27,028 [DEBUG] artifactory.jcr.NodeLock - Retrying lock on '/prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:content' in 1000ms. 2007-05-18 12:01:28,031 [DEBUG] jackrabbit.core.NodeImpl - Unable to perform locking operation on non-lockable node: /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:content 2007-05-18 12:01:28,031 [DEBUG] artifactory.jcr.NodeLock - Retrying lock on '/prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:content' in 1000ms. 2007-05-18 12:01:29,041 [DEBUG] jackrabbit.core.NodeImpl - Unable to perform locking operation on non-lockable node: /prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:content 2007-05-18 12:01:29,184 [ERROR] quartz.core.JobRunShell - Job DEFAULT.backupJobDetail threw an unhandled Exception: java.lang.RuntimeException: Failed to execute JcrCallback. at org.artifactory.jcr.JcrHelper.doInSession(JcrHelper.java:186) at org.artifactory.repo.JcrRepo.exportToDir(JcrRepo.java:279) at org.artifactory.repo.CentralConfig.backupRepos(CentralConfig.java:168) at org.artifactory.repo.CentralConfig.backupRepos(CentralConfig.java:159) at org.artifactory.backup.BackupJob.execute(BackupJob.java:24) at org.quartz.core.JobRunShell.run(JobRunShell.java:202) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:529) Caused by: java.lang.RuntimeException: Failed to acquire node lock on '/prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:content' after 3 retries. at org.artifactory.jcr.NodeLock.lock(NodeLock.java:79) at org.artifactory.jcr.NodeLock.lock(NodeLock.java:39) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:428) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.repo.JcrRepo$6.doInJcr(JcrRepo.java:285) at org.artifactory.repo.JcrRepo$6.doInJcr(JcrRepo.java:280) at org.artifactory.jcr.JcrHelper.doInSession(JcrHelper.java:182) ... 6 more 2007-05-18 12:01:29,234 [ERROR] quartz.core.ErrorLogger - Job (DEFAULT.backupJobDetail threw an exception. org.quartz.SchedulerException: Job threw an unhandled exception. [See nested exception: java.lang.RuntimeException: Failed to execute JcrCallback.] at org.quartz.core.JobRunShell.run(JobRunShell.java:213) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:529) Caused by: java.lang.RuntimeException: Failed to execute JcrCallback. at org.artifactory.jcr.JcrHelper.doInSession(JcrHelper.java:186) at org.artifactory.repo.JcrRepo.exportToDir(JcrRepo.java:279) at org.artifactory.repo.CentralConfig.backupRepos(CentralConfig.java:168) at org.artifactory.repo.CentralConfig.backupRepos(CentralConfig.java:159) at org.artifactory.backup.BackupJob.execute(BackupJob.java:24) at org.quartz.core.JobRunShell.run(JobRunShell.java:202) ... 1 more Caused by: java.lang.RuntimeException: Failed to acquire node lock on '/prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:content' after 3 retries. at org.artifactory.jcr.NodeLock.lock(NodeLock.java:79) at org.artifactory.jcr.NodeLock.lock(NodeLock.java:39) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:428) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.repo.JcrRepo$6.doInJcr(JcrRepo.java:285) at org.artifactory.repo.JcrRepo$6.doInJcr(JcrRepo.java:280) at org.artifactory.jcr.JcrHelper.doInSession(JcrHelper.java:182) ... 6 more 2007-05-18 12:01:30,050 [DEBUG] query.lucene.MultiIndex - Flushing index after being idle for 3026 ms. 2007-05-18 12:01:30,069 [DEBUG] query.lucene.AbstractIndex - closing IndexReader. 2007-05-18 12:01:30,079 [DEBUG] query.lucene.AbstractIndex - committing IndexWriter. 2007-05-18 12:01:30,081 [DEBUG] query.lucene.AbstractIndex - merging segments _0 (1 docs) _1 (1 docs) _2 (1 docs) _3 (1 docs) _4 (1 docs) _5 (1 docs) _6 (1 docs) _7 (1 docs) into _8 (8 docs) 2007-05-18 12:01:30,094 [DEBUG] query.lucene.IndexMerger - index added: name=_gd, numDocs=8 2007-05-18 12:01:30,094 [DEBUG] query.lucene.IndexMerger - requesting merge for [_g4:311, _g5:311, _g6:5, _g7:311, _g8:311, _g9:311, _ga:311, _gb:311, _gc:311, _gd:8] 2007-05-18 12:01:30,094 [DEBUG] query.lucene.IndexMerger - merge queue now contains 1 tasks. 2007-05-18 12:01:30,094 [DEBUG] query.lucene.IndexMerger - accepted merge request 2007-05-18 12:01:30,094 [DEBUG] query.lucene.IndexMerger - create new index 2007-05-18 12:01:30,094 [DEBUG] query.lucene.MultiIndex - Committed in-memory index in 29ms. 2007-05-18 12:01:30,157 [DEBUG] query.lucene.IndexMerger - get index readers from MultiIndex 2007-05-18 12:01:30,714 [INFO ] query.lucene.IndexMerger - merged 1929 documents in 459 ms into _ge. 2007-05-18 12:01:30,714 [DEBUG] query.lucene.IndexMerger - replace indexes 2007-05-18 12:01:30,718 [DEBUG] query.lucene.AbstractIndex - closing IndexWriter. 2007-05-18 12:01:30,719 [DEBUG] query.lucene.IndexMerger - index added: name=_ge, numDocs=1929 2007-05-18 12:01:34,081 [DEBUG] query.lucene.MultiIndex - Flushing index after being idle for 3362 ms. 2007-05-18 12:03:49,103 [DEBUG] artifactory.maven.WagonManagerTempArtifactsCleaner - WagonManager temp artifacts cleaner deleted 0 file(s).

    JFrog JIRA | 10 years ago | Peter Lynch
    java.lang.RuntimeException: Failed to execute JcrCallback.
  3. 0

    Our backup was running fine for a few weeks. I then started to deploy some of our artifacts to the repo and it seems that around that time the backup started failing: 2007-06-06 23:00:00,386 [DEBUG] artifactory.jcr.NodeLock - Locked node '/release-repo' with session org.apache.jackrabbit.core.XASessionImpl@15916e4 2007-06-06 23:00:00,400 [DEBUG] artifactory.jcr.NodeLock - Locked node '/release-repo/foo' with session org.apache.jackrabbit.core.XASessionImpl@15916e4 2007-06-06 23:00:00,475 [DEBUG] artifactory.jcr.NodeLock - Retrying lock on '/release-repo/foo/bar/1/project-1.pom/jcr:content' in 1000ms. 2007-06-06 23:00:01,482 [DEBUG] artifactory.jcr.NodeLock - Retrying lock on '/release-repo/foo/bar/1/project-1.pom/jcr:content' in 1000ms. 2007-06-06 23:00:02,495 [ERROR] quartz.core.JobRunShell - Job DEFAULT.backupJobDetail threw an unhandled Exception: java.lang.RuntimeException: Failed to execute JcrCallback. at org.artifactory.jcr.JcrHelper.doInSession(JcrHelper.java:186) at org.artifactory.repo.JcrRepo.exportToDir(JcrRepo.java:279) at org.artifactory.repo.CentralConfig.backupRepos(CentralConfig.java:168) at org.artifactory.repo.CentralConfig.backupRepos(CentralConfig.java:159) at org.artifactory.backup.BackupJob.execute(BackupJob.java:24) at org.quartz.core.JobRunShell.run(JobRunShell.java:202) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:529) Caused by: java.lang.RuntimeException: Failed to acquire node lock on '/release-repo/foo/bar/1/project-1.pom/jcr:content' after 3 retries. at org.artifactory.jcr.NodeLock.lock(NodeLock.java:79) at org.artifactory.jcr.NodeLock.lock(NodeLock.java:39) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:428) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.repo.JcrRepo$6.doInJcr(JcrRepo.java:285) at org.artifactory.repo.JcrRepo$6.doInJcr(JcrRepo.java:280) at org.artifactory.jcr.JcrHelper.doInSession(JcrHelper.java:182) ... 6 more 2007-06-06 23:00:02,514 [ERROR] quartz.core.ErrorLogger - Job (DEFAULT.backupJobDetail threw an exception. org.quartz.SchedulerException: Job threw an unhandled exception. [See nested exception: java.lang.RuntimeException: Failed to execute JcrCallback.] at org.quartz.core.JobRunShell.run(JobRunShell.java:213) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:529) Caused by: java.lang.RuntimeException: Failed to execute JcrCallback. at org.artifactory.jcr.JcrHelper.doInSession(JcrHelper.java:186) at org.artifactory.repo.JcrRepo.exportToDir(JcrRepo.java:279) at org.artifactory.repo.CentralConfig.backupRepos(CentralConfig.java:168) at org.artifactory.repo.CentralConfig.backupRepos(CentralConfig.java:159) at org.artifactory.backup.BackupJob.execute(BackupJob.java:24) at org.quartz.core.JobRunShell.run(JobRunShell.java:202) ... 1 more Caused by: java.lang.RuntimeException: Failed to acquire node lock on '/release-repo/foo/bar/1/project-1.pom/jcr:content' after 3 retries. at org.artifactory.jcr.NodeLock.lock(NodeLock.java:79) at org.artifactory.jcr.NodeLock.lock(NodeLock.java:39) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:428) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.repo.JcrRepo$6.doInJcr(JcrRepo.java:285) at org.artifactory.repo.JcrRepo$6.doInJcr(JcrRepo.java:280) at org.artifactory.jcr.JcrHelper.doInSession(JcrHelper.java:182) ... 6 more

    JFrog JIRA | 10 years ago | Jorg Heymans
    java.lang.RuntimeException: Failed to execute JcrCallback.
  4. Speed up your debug routine!

    Automated exception search integrated into your IDE

  5. 0

    Our backup was running fine for a few weeks. I then started to deploy some of our artifacts to the repo and it seems that around that time the backup started failing: 2007-06-06 23:00:00,386 [DEBUG] artifactory.jcr.NodeLock - Locked node '/release-repo' with session org.apache.jackrabbit.core.XASessionImpl@15916e4 2007-06-06 23:00:00,400 [DEBUG] artifactory.jcr.NodeLock - Locked node '/release-repo/foo' with session org.apache.jackrabbit.core.XASessionImpl@15916e4 2007-06-06 23:00:00,475 [DEBUG] artifactory.jcr.NodeLock - Retrying lock on '/release-repo/foo/bar/1/project-1.pom/jcr:content' in 1000ms. 2007-06-06 23:00:01,482 [DEBUG] artifactory.jcr.NodeLock - Retrying lock on '/release-repo/foo/bar/1/project-1.pom/jcr:content' in 1000ms. 2007-06-06 23:00:02,495 [ERROR] quartz.core.JobRunShell - Job DEFAULT.backupJobDetail threw an unhandled Exception: java.lang.RuntimeException: Failed to execute JcrCallback. at org.artifactory.jcr.JcrHelper.doInSession(JcrHelper.java:186) at org.artifactory.repo.JcrRepo.exportToDir(JcrRepo.java:279) at org.artifactory.repo.CentralConfig.backupRepos(CentralConfig.java:168) at org.artifactory.repo.CentralConfig.backupRepos(CentralConfig.java:159) at org.artifactory.backup.BackupJob.execute(BackupJob.java:24) at org.quartz.core.JobRunShell.run(JobRunShell.java:202) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:529) Caused by: java.lang.RuntimeException: Failed to acquire node lock on '/release-repo/foo/bar/1/project-1.pom/jcr:content' after 3 retries. at org.artifactory.jcr.NodeLock.lock(NodeLock.java:79) at org.artifactory.jcr.NodeLock.lock(NodeLock.java:39) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:428) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.repo.JcrRepo$6.doInJcr(JcrRepo.java:285) at org.artifactory.repo.JcrRepo$6.doInJcr(JcrRepo.java:280) at org.artifactory.jcr.JcrHelper.doInSession(JcrHelper.java:182) ... 6 more 2007-06-06 23:00:02,514 [ERROR] quartz.core.ErrorLogger - Job (DEFAULT.backupJobDetail threw an exception. org.quartz.SchedulerException: Job threw an unhandled exception. [See nested exception: java.lang.RuntimeException: Failed to execute JcrCallback.] at org.quartz.core.JobRunShell.run(JobRunShell.java:213) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:529) Caused by: java.lang.RuntimeException: Failed to execute JcrCallback. at org.artifactory.jcr.JcrHelper.doInSession(JcrHelper.java:186) at org.artifactory.repo.JcrRepo.exportToDir(JcrRepo.java:279) at org.artifactory.repo.CentralConfig.backupRepos(CentralConfig.java:168) at org.artifactory.repo.CentralConfig.backupRepos(CentralConfig.java:159) at org.artifactory.backup.BackupJob.execute(BackupJob.java:24) at org.quartz.core.JobRunShell.run(JobRunShell.java:202) ... 1 more Caused by: java.lang.RuntimeException: Failed to acquire node lock on '/release-repo/foo/bar/1/project-1.pom/jcr:content' after 3 retries. at org.artifactory.jcr.NodeLock.lock(NodeLock.java:79) at org.artifactory.jcr.NodeLock.lock(NodeLock.java:39) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:428) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450) at org.artifactory.repo.JcrRepo$6.doInJcr(JcrRepo.java:285) at org.artifactory.repo.JcrRepo$6.doInJcr(JcrRepo.java:280) at org.artifactory.jcr.JcrHelper.doInSession(JcrHelper.java:182) ... 6 more

    JFrog JIRA | 10 years ago | Jorg Heymans
    java.lang.RuntimeException: Failed to execute JcrCallback.

    Root Cause Analysis

    1. java.lang.RuntimeException

      Failed to acquire node lock on '/prefix-repo-dev-release/com/example/common/spring/prefix-common-spring-web/1.0.2/prefix-common-spring-web-1.0.2.jar/jcr:content' after 3 retries.

      at org.artifactory.jcr.NodeLock.lock()
    2. org.artifactory.jcr
      JcrHelper.dump
      1. org.artifactory.jcr.NodeLock.lock(NodeLock.java:79)
      2. org.artifactory.jcr.NodeLock.lock(NodeLock.java:39)
      3. org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:428)
      4. org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450)
      5. org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450)
      6. org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450)
      7. org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450)
      8. org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450)
      9. org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450)
      10. org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450)
      11. org.artifactory.jcr.JcrHelper.dump(JcrHelper.java:450)
      11 frames
    3. org.artifactory.repo
      JcrRepo$6.doInJcr
      1. org.artifactory.repo.JcrRepo$6.doInJcr(JcrRepo.java:285)
      2. org.artifactory.repo.JcrRepo$6.doInJcr(JcrRepo.java:280)
      2 frames
    4. org.artifactory.jcr
      JcrHelper.doInSession
      1. org.artifactory.jcr.JcrHelper.doInSession(JcrHelper.java:182)
      1 frame
    5. org.artifactory.repo
      CentralConfig.backupRepos
      1. org.artifactory.repo.JcrRepo.exportToDir(JcrRepo.java:279)
      2. org.artifactory.repo.CentralConfig.backupRepos(CentralConfig.java:168)
      3. org.artifactory.repo.CentralConfig.backupRepos(CentralConfig.java:159)
      3 frames
    6. org.artifactory.backup
      BackupJob.execute
      1. org.artifactory.backup.BackupJob.execute(BackupJob.java:24)
      1 frame
    7. quartz
      SimpleThreadPool$WorkerThread.run
      1. org.quartz.core.JobRunShell.run(JobRunShell.java:202)
      2. org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:529)
      2 frames