Hi,
thank you for the reply.
I attache the full DEBUG logs, from the creation of the node
5462ac5e-ff4d-4489-9423-427f1e3d4520 to the exception.
if you please can have a look at the entries, I do not see any other
exception beofore the "unable to update item"...
The node is created at line 1 of the log.
Then all the items are added and cached.
Just before the exception, I see another creation of the same node
5462ac5e-ff4d-4489-9423-427f1e3d4520 (line 320).
It seems that the node is not found in the DB and it is trying to
recreate it??
Do I have to configure MySQL in a custom way to use the bundles??
Lorenzo
Stefan Guggisberg ha scritto:
hi lorenzo
On Tue, Apr 29, 2008 at 6:32 PM, Lorenzo Dini <[EMAIL PROTECTED]> wrote:
Hi all,
I get an exception while saving nodes with MySqlPersistenceManager that it
does not happen if I use DerbyPersistenceManager.
The weird thing is that executing the same code several times under the
same conditions (the code stores a long sequence of nodes), the exception
not always at the same point but happens randomly on different nodes.
Cannot be the DB connection because the DB is on the same machine.
This is the stacktrace:
RepositoryException:
/etics:volatiles/etics:projects[2]/etics:components[6]: unable to update
item.: a057d13d-c342-4b07-b7d6-ed088b4f6b20
org.apache.jackrabbit.core.ItemImpl.save(ItemImpl.java:1258)
org.etics.repository.webservice.persistency.SubmissionManager.storeConfigurations(SubmissionManager.java:590)
org.etics.repository.webservice.persistency.SubmissionManager.storeComponents(SubmissionManager.java:511)
org.etics.repository.webservice.persistency.SubmissionManager.storeProjects(SubmissionManager.java:301)
org.etics.repository.webservice.persistency.SubmissionManager.saveArtefactStructure(SubmissionManager.java:160)
org.etics.repository.webservice.persistency.SubmissionQueue$SubmissionRun.run(SubmissionQueue.java:189)
Debugging a bit I saw the real exception: NoSuchItemStateException in
AbstractBundlePersistenceManager.java:559
553 if (state.isNode()) {
554 NodeId nodeId = (NodeId) state.getId();
555 NodePropBundle bundle = (NodePropBundle) modified.get(nodeId);
556 if (bundle == null) {
557 bundle = getBundle(nodeId);
558 if (bundle == null) {
559 throw new NoSuchItemStateException(nodeId.toString());
560 }
561 modified.put(nodeId, bundle);
562 }
563 bundle.update((NodeState) state);
564 }
This is my configuration:
<PersistenceManager
class="org.apache.jackrabbit.core.persistence.bundle.MySqlPersistenceManager">
<param name="bundleCacheSize" value="8"/>
<param name="consistencyCheck" value="true"/>
<param name="minBlobSize" value="16384"/>
<param name="driver" value="com.mysql.jdbc.Driver"/>
<param name="url" value="jdbc:mysql://localhost:3306/bundle"/>
<param name="user" value="***"/>
<param name="password" value="***"/>
<param name="schema" value="mysql"/>
<param name="errorHandling" value=""/>
<param name="schemaObjectPrefix" value="${wsp.name}_"/>
</PersistenceManager>
libraries:
jackrabbit-api-1.4.jar
jackrabbit-core-1.4.2.jar
jackrabbit-jcr-commons-1.4.2.jar
jackrabbit-spi-1.4.jar
jackrabbit-spi-commons-1.4.jar
jackrabbit-text-extractors-1.4.jar
lucene-core-2.2.0.jar
MySQL Server Version: 4.1.20
I tried with both:
mysql-connector-java-5.1.6-bin.jar
and
mysql-connector-java-3.1.10-bin.jar.
Without changing anything, but using Derby, it works:
<PersistenceManager
class="org.apache.jackrabbit.core.persistence.bundle.DerbyPersistenceManager">
<param name="url" value="jdbc:derby:{wsp.name}/db;create=true"/>
<param name="schemaObjectPrefix" value="${wsp.name}"/>
</PersistenceManager>
I have been using the same code with Jackrabbit 1.3 for more than one year
now without problems (SimpleDBPersistanceManager on top of the same MySQL).
What can it be??
that's hard to tell without getting some more information. all i can
say is that reading
an existing bundle from the db fails for some reason.
if you haven't done so already please increase the logging level. you should
find more detailed information regarding the issue causing the failure
in the log.
could you please provide all relevant log entries?
does the failure occur after a certain period of time? if yes it could
be related
to the auto-reconnect logic. is the db completely left alone or is it backed up/
restarted periodically?
last but not least:
i know it's difficult but is there a chance that you could put
together a simple
test case for reproducing the issue?
cheers
stefan
Thank you for your help.
Lorenzo
--
*Lorenzo Dini*
CERN - European Organization for Nuclear Research
Information Technology Department
CH-1211 Geneva 23
Building 28 - Office 1-007
Phone: +41 (0) 22 7674384
Fax: +41 (0) 22 7668847
E-mail: [EMAIL PROTECTED]
--
*Lorenzo Dini*
CERN - European Organization for Nuclear Research
Information Technology Department
CH-1211 Geneva 23
Building 28 - Office 1-007
Phone: +41 (0) 22 7674384
Fax: +41 (0) 22 7668847
E-mail: [EMAIL PROTECTED]
18:46:20,844 [DEBUG] jackrabbit.core.ItemManager:750 - created item
5462ac5e-ff4d-4489-9423-427f1e3d4520
18:46:20,845 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
5462ac5e-ff4d-4489-9423-427f1e3d4520
18:46:20,845 [DEBUG] jackrabbit.core.ItemManager:750 - created item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.jcp.org/jcr/1.0}primaryType
18:46:20,845 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.jcp.org/jcr/1.0}primaryType
18:46:20,846 [DEBUG] jackrabbit.core.ItemManager:750 - created item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.jcp.org/jcr/1.0}mixinTypes
18:46:20,846 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.jcp.org/jcr/1.0}mixinTypes
18:46:20,846 [DEBUG] jackrabbit.core.ItemManager:750 - created item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.jcp.org/jcr/1.0}uuid
18:46:20,846 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.jcp.org/jcr/1.0}uuid
18:46:20,847 [DEBUG] jackrabbit.core.ItemManager:750 - created item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}licence
18:46:20,847 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}licence
18:46:20,847 [DEBUG] jackrabbit.core.ItemManager:772 - destroyed item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}licence
18:46:20,848 [DEBUG] jackrabbit.core.ItemManager:702 - removing item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}licence from
cache
18:46:20,849 [DEBUG] jackrabbit.core.ItemManager:750 - created item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}status
18:46:20,849 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}status
18:46:20,849 [DEBUG] jackrabbit.core.ItemManager:772 - destroyed item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}status
18:46:20,849 [DEBUG] jackrabbit.core.ItemManager:702 - removing item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}status from cache
18:46:20,850 [DEBUG] jackrabbit.core.ItemManager:750 - created item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}author
18:46:20,850 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}author
18:46:20,850 [DEBUG] jackrabbit.core.ItemManager:750 - created item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}name
18:46:20,851 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}name
18:46:20,851 [DEBUG] jackrabbit.core.ItemManager:750 - created item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}visibility
18:46:20,851 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}visibility
18:46:20,852 [DEBUG] jackrabbit.core.ItemManager:750 - created item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}creation
18:46:20,852 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}creation
18:46:20,852 [DEBUG] jackrabbit.core.ItemManager:750 - created item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}lastModified
18:46:20,852 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}lastModified
18:46:20,853 [DEBUG] jackrabbit.core.ItemManager:750 - created item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}location
18:46:20,853 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
5462ac5e-ff4d-4489-9423-427f1e3d4520/{http://www.eu-etics.org/}location
18:46:20,855 [DEBUG] persistence.bundle.AbstractBundlePersistenceManager:705 -
stored bundle 5462ac5e-ff4d-4489-9423-427f1e3d4520
18:46:20,856 [DEBUG] persistence.bundle.AbstractBundlePersistenceManager:705 -
stored bundle d89dbc03-3255-4e4b-ad07-fda01761cb32
18:46:20,857 [DEBUG] core.state.SharedItemStateManager:708 - persisting change
log {#addedStates=10, #modifiedStates=1, #deletedStates=0, #modifiedRefs=0}
took 3ms
18:46:20,857 [DEBUG] core.observation.ObservationDispatcher:196 - notifying 2
synchronous listeners.
18:46:20,857 [DEBUG] jackrabbit.core.SearchManager:389 - onEvent: indexing
started
18:46:20,858 [DEBUG] jackrabbit.core.SearchManager:504 - onEvent: indexing
finished in 0 ms.
18:46:20,859 [DEBUG] core.observation.ObservationDispatcher:140 - got
EventStateCollection
18:46:20,859 [DEBUG] core.observation.ObservationDispatcher:141 - event
delivery to 1 consumers started...
18:46:20,859 [DEBUG] core.observation.ObservationDispatcher:152 - event
delivery finished.
18:46:20,862 [DEBUG] query.lucene.QueryImpl:106 - Executing query:
+ Root node
+ Select properties: *
+ PathQueryNode
+ LocationStepQueryNode: NodeTest={} Descendants=false Index=NONE
+ LocationStepQueryNode: NodeTest={http://www.eu-etics.org/}volatiles
Descendants=false Index=1
+ LocationStepQueryNode: NodeTest={http://www.eu-etics.org/}projects
Descendants=false Index=1
+ LocationStepQueryNode: NodeTest={http://www.eu-etics.org/}subsystems
Descendants=false Index=5
+ LocationStepQueryNode: NodeTest={http://www.eu-etics.org/}components
Descendants=false Index=2
+ LocationStepQueryNode: NodeTest={http://www.eu-etics.org/}configurations
Descendants=false Index=1
+ LocationStepQueryNode: NodeTest={http://www.eu-etics.org/}platforms
Descendants=false Index=1
+ LocationStepQueryNode: NodeTest={http://www.eu-etics.org/}jobs
Descendants=false Index=1
+ LocationStepQueryNode: NodeTest={http://www.eu-etics.org/}packageGroup
Descendants=false Index=NONE
+ LocationStepQueryNode: NodeTest={http://www.eu-etics.org/}items
Descendants=false Index=NONE
+ AndQueryNode
+ RelationQueryNode: Op: = [EMAIL PROTECTED]://www.eu-etics.org/}name
Type=STRING Value=glite-wms-utils-jobid-3.1.3-1
+ RelationQueryNode: Op: = [EMAIL PROTECTED]://www.eu-etics.org/}type
Type=STRING Value=tar.gz
18:46:20,862 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
glite-wms-utils-jobid-3.1.3-1 as is.
18:46:20,862 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
glite-wms-utils-jobid-3.1.3-1 as is.
18:46:20,863 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
glite-wms-utils-jobid-3.1.3-1 as is.
18:46:20,863 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
glite-wms-utils-jobid-3.1.3-1 as is.
18:46:20,864 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
glite-wms-utils-jobid-3.1.3-1 as is.
18:46:20,864 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
glite-wms-utils-jobid-3.1.3-1 as is.
18:46:20,864 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
glite-wms-utils-jobid-3.1.3-1 as is.
18:46:20,865 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
glite-wms-utils-jobid-3.1.3-1 as is.
18:46:20,865 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
tar.gz as is.
18:46:20,865 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
tar.gz as is.
18:46:20,866 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
tar.gz as is.
18:46:20,866 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
tar.gz as is.
18:46:20,866 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
tar.gz as is.
18:46:20,867 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
tar.gz as is.
18:46:20,867 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
tar.gz as is.
18:46:20,867 [DEBUG] query.lucene.QueryResultImpl:272 - getResults(2147483647)
18:46:20,868 [DEBUG] query.lucene.AbstractIndex:289 - closing IndexReader.
18:46:20,890 [DEBUG] query.lucene.AbstractIndex:188 - Inverted document in 13 ms
18:46:20,891 [DEBUG] query.lucene.AbstractIndex:188 - Inverted document in 8 ms
18:46:20,892 [DEBUG] query.lucene.AbstractIndex:574 - merging segments _ram_0
(1 docs) _ram_1 (1 docs) into _q (2 docs)
18:46:20,892 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: now checkpoint "segments_11" [isCommit = true]
18:46:20,893 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_k.fnm": pre-incr count is 1
18:46:20,893 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_k.fdx": pre-incr count is 1
18:46:20,894 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_k.fdt": pre-incr count is 1
18:46:20,894 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_k.tii": pre-incr count is 1
18:46:20,894 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_k.tis": pre-incr count is 1
18:46:20,895 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_k.frq": pre-incr count is 1
18:46:20,895 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_k.prx": pre-incr count is 1
18:46:20,895 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_k.nrm": pre-incr count is 1
18:46:20,896 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_k_2.del": pre-incr count is 1
18:46:20,896 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_l.fnm": pre-incr count is 1
18:46:20,897 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_l.fdx": pre-incr count is 1
18:46:20,897 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_l.fdt": pre-incr count is 1
18:46:20,897 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_l.tii": pre-incr count is 1
18:46:20,898 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_l.tis": pre-incr count is 1
18:46:20,898 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_l.frq": pre-incr count is 1
18:46:20,898 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_l.prx": pre-incr count is 1
18:46:20,899 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_l.nrm": pre-incr count is 1
18:46:20,899 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_m.fnm": pre-incr count is 1
18:46:20,900 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_m.fdx": pre-incr count is 1
18:46:20,900 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_m.fdt": pre-incr count is 1
18:46:20,900 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_m.tii": pre-incr count is 1
18:46:20,901 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_m.tis": pre-incr count is 1
18:46:20,901 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_m.frq": pre-incr count is 1
18:46:20,902 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_m.prx": pre-incr count is 1
18:46:20,902 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_m.nrm": pre-incr count is 1
18:46:20,902 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_n.fnm": pre-incr count is 1
18:46:20,903 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_n.fdx": pre-incr count is 1
18:46:20,903 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_n.fdt": pre-incr count is 1
18:46:20,903 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_n.tii": pre-incr count is 1
18:46:20,904 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_n.tis": pre-incr count is 1
18:46:20,904 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_n.frq": pre-incr count is 1
18:46:20,905 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_n.prx": pre-incr count is 1
18:46:20,905 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_n.nrm": pre-incr count is 1
18:46:20,905 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_o.fnm": pre-incr count is 1
18:46:20,906 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_o.fdx": pre-incr count is 1
18:46:20,906 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_o.fdt": pre-incr count is 1
18:46:20,907 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_o.tii": pre-incr count is 1
18:46:20,907 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_o.tis": pre-incr count is 1
18:46:20,907 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_o.frq": pre-incr count is 1
18:46:20,908 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_o.prx": pre-incr count is 1
18:46:20,909 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_o.nrm": pre-incr count is 1
18:46:20,909 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_p.fnm": pre-incr count is 1
18:46:20,909 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_p.fdx": pre-incr count is 1
18:46:20,910 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_p.fdt": pre-incr count is 1
18:46:20,910 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_p.tii": pre-incr count is 1
18:46:20,911 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_p.tis": pre-incr count is 1
18:46:20,911 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_p.frq": pre-incr count is 1
18:46:20,911 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_p.prx": pre-incr count is 1
18:46:20,912 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_p.nrm": pre-incr count is 1
18:46:20,912 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_q.fnm": pre-incr count is 0
18:46:20,912 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_q.fdx": pre-incr count is 0
18:46:20,913 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_q.fdt": pre-incr count is 0
18:46:20,913 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_q.tii": pre-incr count is 0
18:46:20,914 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_q.tis": pre-incr count is 0
18:46:20,914 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_q.frq": pre-incr count is 0
18:46:20,914 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_q.prx": pre-incr count is 0
18:46:20,915 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: IncRef "_q.nrm": pre-incr count is 0
18:46:20,915 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: deleteCommits: now remove commit "segments_10"
18:46:20,915 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_k.fnm": pre-decr count is 2
18:46:20,916 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_k.fdx": pre-decr count is 2
18:46:20,916 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_k.fdt": pre-decr count is 2
18:46:20,917 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_k.tii": pre-decr count is 2
18:46:20,917 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_k.tis": pre-decr count is 2
18:46:20,917 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_k.frq": pre-decr count is 2
18:46:20,918 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_k.prx": pre-decr count is 2
18:46:20,918 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_k.nrm": pre-decr count is 2
18:46:20,918 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_k_2.del": pre-decr count is 2
18:46:20,919 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_l.fnm": pre-decr count is 2
18:46:20,919 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_l.fdx": pre-decr count is 2
18:46:20,920 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_l.fdt": pre-decr count is 2
18:46:20,920 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_l.tii": pre-decr count is 2
18:46:20,920 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_l.tis": pre-decr count is 2
18:46:20,921 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_l.frq": pre-decr count is 2
18:46:20,921 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_l.prx": pre-decr count is 2
18:46:20,921 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_l.nrm": pre-decr count is 2
18:46:20,922 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_m.fnm": pre-decr count is 2
18:46:20,922 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_m.fdx": pre-decr count is 2
18:46:20,923 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_m.fdt": pre-decr count is 2
18:46:20,923 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_m.tii": pre-decr count is 2
18:46:20,923 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_m.tis": pre-decr count is 2
18:46:20,924 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_m.frq": pre-decr count is 2
18:46:20,924 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_m.prx": pre-decr count is 2
18:46:20,925 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_m.nrm": pre-decr count is 2
18:46:20,925 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_n.fnm": pre-decr count is 2
18:46:20,925 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_n.fdx": pre-decr count is 2
18:46:20,926 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_n.fdt": pre-decr count is 2
18:46:20,926 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_n.tii": pre-decr count is 2
18:46:20,926 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_n.tis": pre-decr count is 2
18:46:20,927 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_n.frq": pre-decr count is 2
18:46:20,927 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_n.prx": pre-decr count is 2
18:46:20,928 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_n.nrm": pre-decr count is 2
18:46:20,928 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_o.fnm": pre-decr count is 2
18:46:20,929 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_o.fdx": pre-decr count is 2
18:46:20,929 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_o.fdt": pre-decr count is 2
18:46:20,930 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_o.tii": pre-decr count is 2
18:46:20,930 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_o.tis": pre-decr count is 2
18:46:20,930 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_o.frq": pre-decr count is 2
18:46:20,931 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_o.prx": pre-decr count is 2
18:46:20,931 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_o.nrm": pre-decr count is 2
18:46:20,932 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_p.fnm": pre-decr count is 2
18:46:20,932 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_p.fdx": pre-decr count is 2
18:46:20,932 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_p.fdt": pre-decr count is 2
18:46:20,933 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_p.tii": pre-decr count is 2
18:46:20,933 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_p.tis": pre-decr count is 2
18:46:20,933 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_p.frq": pre-decr count is 2
18:46:20,934 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_p.prx": pre-decr count is 2
18:46:20,934 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "_p.nrm": pre-decr count is 2
18:46:20,935 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: DecRef "segments_10": pre-decr count is 1
18:46:20,935 [DEBUG] query.lucene.AbstractIndex:574 - [EMAIL PROTECTED]
Thread-16: delete "segments_10"
18:46:20,935 [DEBUG] query.lucene.AbstractIndex:219 - closing IndexWriter.
18:46:20,941 [DEBUG] core.query.QueryImpl:183 - executed in 0.08 s.
(/jcr:root/etics:volatiles[1]/etics:projects[1]/etics:subsystems[5]/etics:components[2]/etics:configurations[1]/etics:platforms[1]/etics:jobs[1]/etics:packageGroup/etics:items[(@etics:name='glite-wms-utils-jobid-3.1.3-1')
and (@etics:type='tar.gz')])
18:46:20,941 [DEBUG] webservice.persistency.SubmissionManager:1054 - Storing
item:glite-wms-utils-jobid-3.1.3-1
18:46:20,943 [DEBUG] query.lucene.QueryImpl:106 - Executing query:
+ Root node
+ Select properties: {http://www.eu-etics.org/}name
+ PathQueryNode
+ LocationStepQueryNode: NodeTest=* Descendants=true Index=NONE
+ RelationQueryNode: Op: = [EMAIL
PROTECTED]://www.eu-etics.org/}downloadFrom Type=STRING
Value=default/org.glite/org.glite.wms-utils.jobid/3.1.3/slc4_ia32_gcc346/glite-wms-utils-jobid-3.1.3-1.tar.gz
18:46:20,943 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
default/org.glite/org.glite.wms-utils.jobid/3.1.3/slc4_ia32_gcc346/glite-wms-utils-jobid-3.1.3-1.tar.gz
as is.
18:46:20,944 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
default/org.glite/org.glite.wms-utils.jobid/3.1.3/slc4_ia32_gcc346/glite-wms-utils-jobid-3.1.3-1.tar.gz
as is.
18:46:20,944 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
default/org.glite/org.glite.wms-utils.jobid/3.1.3/slc4_ia32_gcc346/glite-wms-utils-jobid-3.1.3-1.tar.gz
as is.
18:46:20,944 [DEBUG] query.lucene.QueryResultImpl:272 - getResults(2147483647)
18:46:20,945 [DEBUG] core.query.QueryImpl:183 - executed in 0.00 s. (select
etics:name from nt:base where
etics:downloadFrom='default/org.glite/org.glite.wms-utils.jobid/3.1.3/slc4_ia32_gcc346/glite-wms-utils-jobid-3.1.3-1.tar.gz')
18:46:20,946 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792
18:46:20,946 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792
18:46:20,947 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.jcp.org/jcr/1.0}primaryType
18:46:20,947 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.jcp.org/jcr/1.0}primaryType
18:46:20,947 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}name
18:46:20,947 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}name
18:46:20,948 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}type
18:46:20,949 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}type
18:46:20,949 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}size
18:46:20,949 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}size
18:46:20,950 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}externalLocation
18:46:20,950 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}externalLocation
18:46:20,950 [DEBUG] jackrabbit.core.ItemManager:772 - destroyed item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}externalLocation
18:46:20,951 [DEBUG] jackrabbit.core.ItemManager:702 - removing item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}externalLocation
from cache
18:46:20,951 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}internalLocation
18:46:20,951 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}internalLocation
18:46:21,104 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}locationType
18:46:21,104 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}locationType
18:46:21,105 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}location
18:46:21,105 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}location
18:46:21,105 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}downloadFrom
18:46:21,105 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}downloadFrom
18:46:21,106 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}author
18:46:21,106 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}author
18:46:21,106 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}visibility
18:46:21,107 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}visibility
18:46:21,107 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}creation
18:46:21,107 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}creation
18:46:21,108 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}lastModified
18:46:21,108 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}lastModified
18:46:21,108 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.jcp.org/jcr/1.0}mixinTypes
18:46:21,108 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.jcp.org/jcr/1.0}mixinTypes
18:46:21,109 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.jcp.org/jcr/1.0}uuid
18:46:21,110 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.jcp.org/jcr/1.0}uuid
18:46:21,112 [DEBUG] query.lucene.QueryImpl:106 - Executing query:
+ Root node
+ Select properties: *
+ PathQueryNode
+ LocationStepQueryNode: NodeTest={} Descendants=false Index=NONE
+ LocationStepQueryNode: NodeTest={http://www.eu-etics.org/}directories
Descendants=false Index=NONE
+ RelationQueryNode: Op: = [EMAIL PROTECTED]://www.eu-etics.org/}name
Type=STRING Value=default
18:46:21,113 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
default as is.
18:46:21,113 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
default as is.
18:46:21,113 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
default as is.
18:46:21,114 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
default as is.
18:46:21,114 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
default as is.
18:46:21,114 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
default as is.
18:46:21,115 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
default as is.
18:46:21,115 [DEBUG] query.lucene.LuceneQueryBuilder:1050 - Using literal
default as is.
18:46:21,115 [DEBUG] query.lucene.QueryResultImpl:272 - getResults(2147483647)
18:46:21,118 [DEBUG] core.query.QueryImpl:183 - executed in 0.01 s.
(/jcr:root/etics:[EMAIL PROTECTED]:name='default'])
18:46:21,118 [DEBUG] jackrabbit.core.ItemManager:750 - created item
7daac8c6-7b0b-4028-8445-1a33e024afbf
18:46:21,119 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
7daac8c6-7b0b-4028-8445-1a33e024afbf
18:46:21,119 [DEBUG] jackrabbit.core.ItemManager:750 - created item
7daac8c6-7b0b-4028-8445-1a33e024afbf/{http://www.eu-etics.org/}lastModified
18:46:21,120 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
7daac8c6-7b0b-4028-8445-1a33e024afbf/{http://www.eu-etics.org/}lastModified
18:46:21,124 [DEBUG] jackrabbit.core.ItemManager:761 - invalidated item
5462ac5e-ff4d-4489-9423-427f1e3d4520
18:46:21,124 [DEBUG] jackrabbit.core.ItemManager:702 - removing item
5462ac5e-ff4d-4489-9423-427f1e3d4520 from cache
18:46:21,125 [DEBUG] jackrabbit.core.ItemImpl:1257 - /: unable to update item.
18:46:21,126 [DEBUG] jackrabbit.core.ItemManager:761 - invalidated item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}lastModified
18:46:21,126 [DEBUG] jackrabbit.core.ItemManager:702 - removing item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}lastModified
from cache
18:46:21,127 [DEBUG] jackrabbit.core.ItemManager:761 - invalidated item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}location
18:46:21,127 [DEBUG] jackrabbit.core.ItemManager:702 - removing item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}location from
cache
18:46:21,127 [DEBUG] jackrabbit.core.ItemManager:761 - invalidated item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}locationType
18:46:21,128 [DEBUG] jackrabbit.core.ItemManager:702 - removing item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}locationType
from cache
18:46:21,128 [DEBUG] jackrabbit.core.ItemManager:761 - invalidated item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}size
18:46:21,129 [DEBUG] jackrabbit.core.ItemManager:702 - removing item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}size from cache
18:46:21,129 [DEBUG] jackrabbit.core.ItemManager:761 - invalidated item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}downloadFrom
18:46:21,129 [DEBUG] jackrabbit.core.ItemManager:702 - removing item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}downloadFrom
from cache
18:46:21,130 [DEBUG] jackrabbit.core.ItemManager:761 - invalidated item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}visibility
18:46:21,130 [DEBUG] jackrabbit.core.ItemManager:702 - removing item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}visibility from
cache
18:46:21,131 [DEBUG] jackrabbit.core.ItemManager:761 - invalidated item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}name
18:46:21,131 [DEBUG] jackrabbit.core.ItemManager:702 - removing item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}name from cache
18:46:21,131 [DEBUG] jackrabbit.core.ItemManager:761 - invalidated item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}type
18:46:21,132 [DEBUG] jackrabbit.core.ItemManager:702 - removing item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}type from cache
18:46:21,132 [DEBUG] jackrabbit.core.ItemManager:761 - invalidated item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.jcp.org/jcr/1.0}uuid
18:46:21,132 [DEBUG] jackrabbit.core.ItemManager:702 - removing item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.jcp.org/jcr/1.0}uuid from cache
18:46:21,133 [DEBUG] jackrabbit.core.ItemManager:761 - invalidated item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.jcp.org/jcr/1.0}primaryType
18:46:21,133 [DEBUG] jackrabbit.core.ItemManager:702 - removing item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.jcp.org/jcr/1.0}primaryType
from cache
18:46:21,133 [DEBUG] jackrabbit.core.ItemManager:761 - invalidated item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}internalLocation
18:46:21,134 [DEBUG] jackrabbit.core.ItemManager:702 - removing item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}internalLocation
from cache
18:46:21,134 [DEBUG] jackrabbit.core.ItemManager:761 - invalidated item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.jcp.org/jcr/1.0}mixinTypes
18:46:21,134 [DEBUG] jackrabbit.core.ItemManager:702 - removing item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.jcp.org/jcr/1.0}mixinTypes
from cache
18:46:21,135 [DEBUG] jackrabbit.core.ItemManager:761 - invalidated item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}author
18:46:21,135 [DEBUG] jackrabbit.core.ItemManager:702 - removing item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}author from cache
18:46:21,135 [DEBUG] jackrabbit.core.ItemManager:761 - invalidated item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}creation
18:46:21,136 [DEBUG] jackrabbit.core.ItemManager:702 - removing item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}creation from
cache
18:46:21,136 [DEBUG] jackrabbit.core.ItemManager:761 - invalidated item
c2671f95-630c-4fe6-9561-f5803b4fe792
18:46:21,136 [DEBUG] jackrabbit.core.ItemManager:702 - removing item
c2671f95-630c-4fe6-9561-f5803b4fe792 from cache
18:46:21,138 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}lastModified
18:46:21,139 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}lastModified
18:46:21,140 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}location
18:46:21,140 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}location
18:46:21,141 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}locationType
18:46:21,141 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}locationType
18:46:21,142 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}size
18:46:21,143 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}size
18:46:21,144 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}downloadFrom
18:46:21,144 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}downloadFrom
18:46:21,145 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}visibility
18:46:21,145 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}visibility
18:46:21,146 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}name
18:46:21,147 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}name
18:46:21,147 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}type
18:46:21,148 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}type
18:46:21,149 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.jcp.org/jcr/1.0}uuid
18:46:21,150 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.jcp.org/jcr/1.0}uuid
18:46:21,151 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.jcp.org/jcr/1.0}primaryType
18:46:21,151 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.jcp.org/jcr/1.0}primaryType
18:46:21,152 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}internalLocation
18:46:21,152 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}internalLocation
18:46:21,153 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.jcp.org/jcr/1.0}mixinTypes
18:46:21,153 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.jcp.org/jcr/1.0}mixinTypes
18:46:21,154 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}author
18:46:21,155 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}author
18:46:21,156 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}creation
18:46:21,156 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792/{http://www.eu-etics.org/}creation
18:46:21,157 [DEBUG] jackrabbit.core.ItemManager:750 - created item
c2671f95-630c-4fe6-9561-f5803b4fe792
18:46:21,157 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
c2671f95-630c-4fe6-9561-f5803b4fe792
18:46:21,158 [DEBUG] jackrabbit.core.ItemManager:750 - created item
5462ac5e-ff4d-4489-9423-427f1e3d4520
18:46:21,159 [DEBUG] jackrabbit.core.ItemManager:689 - caching item
5462ac5e-ff4d-4489-9423-427f1e3d4520
18:46:21,184 [ERROR] webservice.persistency.SubmissionManager:164 - Error
storing the artefact structure: /: unable to update item.:
5462ac5e-ff4d-4489-9423-427f1e3d4520
org.apache.jackrabbit.core.ItemImpl.save(ItemImpl.java:1258)
org.etics.repository.webservice.util.Utilities.submitDirectoryStructure(Utilities.java:245)
org.etics.repository.webservice.persistency.SubmissionManager.storeItems(SubmissionManager.java:1101)
org.etics.repository.webservice.persistency.SubmissionManager.storePackageGroup(SubmissionManager.java:868)
org.etics.repository.webservice.persistency.SubmissionManager.addJob(SubmissionManager.java:830)
org.etics.repository.webservice.persistency.SubmissionManager.storeJobs(SubmissionManager.java:742)
org.etics.repository.webservice.persistency.SubmissionManager.storePlatforms(SubmissionManager.java:639)
org.etics.repository.webservice.persistency.SubmissionManager.storeConfigurations(SubmissionManager.java:592)
org.etics.repository.webservice.persistency.SubmissionManager.storeComponents(SubmissionManager.java:511)
org.etics.repository.webservice.persistency.SubmissionManager.storeSubsystems(SubmissionManager.java:426)
org.etics.repository.webservice.persistency.SubmissionManager.storeProjects(SubmissionManager.java:306)
org.etics.repository.webservice.persistency.SubmissionManager.saveArtefactStructure(SubmissionManager.java:160)
org.etics.repository.webservice.persistency.SubmissionQueue$SubmissionRun.run(SubmissionQueue.java:189)
