Hi Marcel,
Marcel Reutegger wrote:
Hi Daniel,
that's quite strange. I tried to reproduce the issue, but was unable
to do so. Is there anything unusual in the log files that might
explain why the queries fail?
you can also re-index the content and see if the query works afterwards:
- stop jackrabbit
- delete the directory <repo-home>/workspaces/default/index
- start jackrabbit
- execute the query
I have embedded Jackrabbit into my application, therefore there is no
need to stop Jackrabbit.
Here is what I did:
1. Deleted all directories below <repo-home>.
2. Started my test case (saving an item to the repository and executing
the query "/jcr:root/interedu/*"). I do not see anything unusal in the
logs below (full debug logging enabled, I have shortened the log a bit)
2007-11-19 14:33:12,595 DEBUG
[org.apache.jackrabbit.core.TransientRepository] - <Initializing
transient repository>
2007-11-19 14:33:12,626 INFO [org.apache.jackrabbit.core.RepositoryImpl]
- <Starting repository...>
2007-11-19 14:33:12,658 INFO
[org.apache.jackrabbit.core.fs.local.LocalFileSystem] - <LocalFileSystem
initialized at path
D:\projects\interedu\workspace\AssetStore\target\test-classes\jcr-repository>
2007-11-19 14:33:12,798 INFO
[org.apache.jackrabbit.core.nodetype.NodeTypeRegistry] - <no custom node
type definitions found>
2007-11-19 14:33:12,814 INFO
[org.apache.jackrabbit.core.fs.local.LocalFileSystem] - <LocalFileSystem
initialized at path
D:\projects\interedu\workspace\AssetStore\target\test-classes\jcr-repository>
2007-11-19 14:33:12,845 INFO
[org.apache.jackrabbit.core.fs.local.LocalFileSystem] - <LocalFileSystem
initialized at path
D:\projects\interedu\workspace\AssetStore\target\test-classes\jcr-repository\versions\blobs>
2007-11-19 14:33:12,923 DEBUG
[org.apache.jackrabbit.core.state.MLRUItemStateCache] -
<[EMAIL PROTECTED] size=1,
664/4194304>
2007-11-19 14:33:12,923 DEBUG
[org.apache.jackrabbit.core.state.MLRUItemStateCache] -
<[EMAIL PROTECTED] size=1,
664/4194304>
2007-11-19 14:33:12,923 INFO [org.apache.jackrabbit.core.RepositoryImpl]
- <initializing workspace 'default'...>
2007-11-19 14:33:12,923 INFO
[org.apache.jackrabbit.core.fs.local.LocalFileSystem] - <LocalFileSystem
initialized at path
D:\projects\interedu\workspace\AssetStore\target\test-classes\jcr-repository\workspaces\default>
2007-11-19 14:33:12,923 INFO
[org.apache.jackrabbit.core.fs.local.LocalFileSystem] - <LocalFileSystem
initialized at path
D:\projects\interedu\workspace\AssetStore\target\test-classes\jcr-repository\workspaces\default\blobs>
2007-11-19 14:33:12,939 DEBUG
[org.apache.jackrabbit.core.state.MLRUItemStateCache] -
<[EMAIL PROTECTED] size=1,
964/4194304>
2007-11-19 14:33:12,954 DEBUG
[org.apache.jackrabbit.core.virtual.AbstractVISProvider] - <item added
to cache. size=1>
2007-11-19 14:33:12,954 DEBUG
[org.apache.jackrabbit.core.virtual.AbstractVISProvider] - <item added
to cache. size=2>
[...]
2007-11-19 14:33:13,017 DEBUG
[org.apache.jackrabbit.core.virtual.AbstractVISProvider] - <item added
to cache. size=92>
2007-11-19 14:33:13,017 DEBUG
[org.apache.jackrabbit.core.virtual.AbstractVISProvider] - <item added
to cache. size=93>
2007-11-19 14:33:13,033 INFO [org.apache.jackrabbit.core.RepositoryImpl]
- <workspace 'default' initialized>
2007-11-19 14:33:13,173 DEBUG
[org.apache.jackrabbit.core.query.lucene.JackrabbitTextExtractor] -
<JackrabbitTextExtractor(org.apache.jackrabbit.extractor.DefaultTextExtractor)>
2007-11-19 14:33:13,204 INFO
[org.apache.jackrabbit.core.fs.local.LocalFileSystem] - <LocalFileSystem
initialized at path
D:\projects\interedu\workspace\AssetStore\target\test-classes\jcr-repository\repository\index>
2007-11-19 14:33:13,220 DEBUG
[org.apache.jackrabbit.core.query.lucene.Recovery] - <RedoLog is empty,
no recovery needed.>
2007-11-19 14:33:13,220 DEBUG
[org.apache.jackrabbit.core.state.MLRUItemStateCache] -
<[EMAIL PROTECTED] size=1,
1264/4194304>
2007-11-19 14:33:13,642 INFO
[org.apache.jackrabbit.core.state.CacheManager] - <resizeAll size=4>
2007-11-19 14:33:13,642 DEBUG
[org.apache.jackrabbit.core.state.CacheManager] -
<[EMAIL PROTECTED] now:4194304
used:476790 access:1397 new:476790>
2007-11-19 14:33:13,642 DEBUG
[org.apache.jackrabbit.core.state.CacheManager] -
<[EMAIL PROTECTED] now:4194304
used:664 access:2 new:131072>
2007-11-19 14:33:13,642 DEBUG
[org.apache.jackrabbit.core.state.CacheManager] -
<[EMAIL PROTECTED] now:4194304
used:1178 access:5 new:131072>
2007-11-19 14:33:13,642 DEBUG
[org.apache.jackrabbit.core.state.CacheManager] -
<[EMAIL PROTECTED] now:4194304
used:3256 access:7 new:131072>
2007-11-19 14:33:13,829 INFO
[org.apache.jackrabbit.core.query.lucene.SearchIndex] - <Index
initialized:
D:\projects\interedu\workspace\AssetStore\target\test-classes\jcr-repository/repository/index>
2007-11-19 14:33:13,829 DEBUG
[org.apache.jackrabbit.core.query.lucene.JackrabbitTextExtractor] -
<JackrabbitTextExtractor(
org.apache.jackrabbit.extractor.XMLTextExtractor,org.apache.jackrabbit.extractor.PlainTextExtractor,org.apache.jackrabbit.extractor.PdfTextExtractor,org.apache.jackrabbit.extractor.OpenOfficeTextExtractor,org.apache.jackrabbit.extractor.MsWordTextExtractor,org.apache.jackrabbit.extractor.MsPowerPointTextExtractor,org.apache.jackrabbit.extractor.MsExcelTextExtractor,org.apache.jackrabbit.extractor.HTMLTextExtractor)>
2007-11-19 14:33:13,861 INFO
[org.apache.jackrabbit.core.fs.local.LocalFileSystem] - <LocalFileSystem
initialized at path
D:\projects\interedu\workspace\AssetStore\target\test-classes\jcr-repository\workspaces\default\index>
2007-11-19 14:33:13,861 DEBUG
[org.apache.jackrabbit.core.query.lucene.Recovery] - <RedoLog is empty,
no recovery needed.>
2007-11-19 14:33:13,861 INFO
[org.apache.jackrabbit.core.query.lucene.SearchIndex] - <Index
initialized:
D:\projects\interedu\workspace\AssetStore\target\test-classes\jcr-repository\workspaces\default/index>
2007-11-19 14:33:13,861 INFO [org.apache.jackrabbit.core.RepositoryImpl]
- <Repository started>
2007-11-19 14:33:13,861 INFO
[org.apache.jackrabbit.core.TransientRepository] - <Transient repository
initialized>
2007-11-19 14:33:13,861 DEBUG
[org.apache.jackrabbit.core.TransientRepository] - <Opening a new session>
2007-11-19 14:33:13,892 DEBUG
[org.apache.jackrabbit.core.state.MLRUItemStateCache] -
<[EMAIL PROTECTED] size=1,
664/4194304>
2007-11-19 14:33:13,892 INFO
[org.apache.jackrabbit.core.TransientRepository] - <Session opened>
2007-11-19 14:33:13,908 DEBUG
[org.springmodules.jcr.jackrabbit.LocalTransactionManager] - <Opened new
session [EMAIL PROTECTED] for JCR
transaction>
2007-11-19 14:33:13,923 DEBUG
[org.springmodules.jcr.jackrabbit.LocalTransactionManager] - <Found
thread-bound session [EMAIL PROTECTED]
for JCR transaction>
2007-11-19 14:33:13,923 DEBUG
[org.springmodules.jcr.jackrabbit.LocalTransactionManager] - <Using
transaction object
[EMAIL PROTECTED]>
2007-11-19 14:33:13,923 DEBUG
[org.springmodules.jcr.jackrabbit.LocalTransactionManager] -
<Participating in existing transaction>
2007-11-19 14:33:13,923 DEBUG [org.springmodules.jcr.JcrTemplate] -
<Found thread-bound Session for JcrTemplate>
2007-11-19 14:33:13,939 DEBUG
[org.apache.jackrabbit.core.state.MLRUItemStateCache] -
<[EMAIL PROTECTED] size=1,
964/4194304>
2007-11-19 14:33:13,970 DEBUG [org.apache.jackrabbit.core.ItemManager] -
<created item cafebabe-cafe-babe-cafe-babecafebabe>
2007-11-19 14:33:13,970 DEBUG [org.apache.jackrabbit.core.ItemManager] -
<caching item cafebabe-cafe-babe-cafe-babecafebabe>
[...]
2007-11-19 14:33:14,001 DEBUG [org.apache.jackrabbit.core.ItemManager] -
<caching item
f39def9f-4dde-4b31-9de5-8f804f493bbd/{http://www.jcp.org/jcr/1.0}predecessors>
2007-11-19 14:33:14,017 DEBUG
[org.apache.jackrabbit.core.HierarchyManagerImpl] - <failed to build
path of f39def9f-4dde-4b31-9de5-8f804f493bbd>
2007-11-19 14:33:14,017 DEBUG [org.apache.jackrabbit.core.ItemManager] -
<created item ebdad629-ac0b-44fe-8438-f0479403c4fb>
2007-11-19 14:33:14,017 DEBUG [org.apache.jackrabbit.core.ItemManager] -
<caching item ebdad629-ac0b-44fe-8438-f0479403c4fb>
2007-11-19 14:33:14,017 DEBUG [org.springmodules.jcr.JcrTemplate] - <Not
closing pre-bound Jcr Session after JcrTemplate>
2007-11-19 14:33:14,017 DEBUG
[org.springmodules.jcr.jackrabbit.LocalTransactionManager] - <Found
thread-bound session [EMAIL PROTECTED]
for JCR transaction>
2007-11-19 14:33:14,017 DEBUG
[org.springmodules.jcr.jackrabbit.LocalTransactionManager] - <Using
transaction object
[EMAIL PROTECTED]>
2007-11-19 14:33:14,017 DEBUG
[org.springmodules.jcr.jackrabbit.LocalTransactionManager] -
<Participating in existing transaction>
2007-11-19 14:33:14,017 DEBUG [org.springmodules.jcr.JcrTemplate] -
<Found thread-bound Session for JcrTemplate>
2007-11-19 14:33:14,017 DEBUG [org.springmodules.jcr.JcrTemplate] - <Not
closing pre-bound Jcr Session after JcrTemplate>
2007-11-19 14:33:14,017 DEBUG
[org.springmodules.jcr.jackrabbit.LocalTransactionManager] - <Found
thread-bound session [EMAIL PROTECTED]
for JCR transaction>
2007-11-19 14:33:14,017 DEBUG
[org.springmodules.jcr.jackrabbit.LocalTransactionManager] - <Using
transaction object
[EMAIL PROTECTED]>
2007-11-19 14:33:14,017 DEBUG
[org.springmodules.jcr.jackrabbit.LocalTransactionManager] -
<Participating in existing transaction>
2007-11-19 14:33:14,017 DEBUG [org.springmodules.jcr.JcrTemplate] -
<Found thread-bound Session for JcrTemplate>
2007-11-19 14:33:14,173 DEBUG
[org.apache.jackrabbit.core.query.lucene.QueryImpl] - <Executing query:
+ Root node
+ Select properties: *
+ PathQueryNode
+ LocationStepQueryNode: NodeTest={} Descendants=false Index=NONE
+ LocationStepQueryNode: NodeTest={}interedu Descendants=false
Index=NONE
+ LocationStepQueryNode: NodeTest=* Descendants=false Index=NONE
>
2007-11-19 14:33:14,189 DEBUG
[org.apache.jackrabbit.core.query.lucene.QueryResultImpl] -
<getResults(2147483647)>
2007-11-19 14:33:14,267 DEBUG
[org.apache.jackrabbit.core.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) _8 (1 docs) _9 (1 docs) _a (1 docs) _b
(1 docs) _c (1 docs) _d (1 docs) _e (1 docs) _f (1 docs) _g (1 docs) _h
(1 docs) _i (1 docs) _j (1 docs) _k (1 docs) _l (1 docs) _m (1 docs) _n
(1 docs) _o (1 docs) _p (1 docs) _q (1 docs) _r (1 docs) _s (1 docs) _t
(1 docs) _u (1 docs) _v (1 docs) _w (1 docs) _x (1 docs) _y (1 docs) _z
(1 docs) _10 (1 docs) _11 (1 docs) _12 (1 docs) _13 (1 docs) _14 (1
docs) _15 (1 docs) _16 (1 docs) _17 (1 docs) _18 (1 docs) _19 (1 docs)
_1a (1 docs) _1b (1 docs) _1c (1 docs) _1d (1 docs) _1e (1 docs) _1f (1
docs) _1g (1 docs) _1h (1 docs) _1i (1 docs) _1j (1 docs) _1k (1 docs)
_1l (1 docs) _1m (1 docs) _1n (1 docs) _1o (1 docs) _1p (1 docs) _1q (1
docs) _1r (1 docs) _1s (1 docs) _1t (1 docs) _1u (1 docs) _1v (1 docs)
_1w (1 docs) _1x (1 docs) _1y (1 docs) _1z (1 docs) _20 (1 docs) _21 (1
docs) _22 (1 docs) _23 (1 docs) _24 (1 docs) _25 (1 docs) _26 (1 docs)
_27 (1 docs) _28 (1 docs) _29 (1 docs) _2a (1 docs) _2b (1 docs) _2c (1
docs) _2d (1 docs) _2e (1 docs) _2f (1 docs) _2g (1 docs) _2h (1 docs)
_2i (1 docs) _2j (1 docs) _2k (1 docs) _2l (1 docs) _2m (1 docs) _2n (1
docs) into _2o (96 docs)>
2007-11-19 14:33:14,283 DEBUG
[org.apache.jackrabbit.core.query.lucene.AbstractIndex] - <closing
IndexWriter.>
2007-11-19 14:33:14,298 DEBUG
[org.apache.jackrabbit.core.query.lucene.AbstractIndex] - <merging
segments _0 (1 docs) into _1 (1 docs)>
2007-11-19 14:33:14,298 DEBUG
[org.apache.jackrabbit.core.query.lucene.AbstractIndex] - <closing
IndexWriter.>
2007-11-19 14:33:14,329 DEBUG
[org.apache.jackrabbit.core.query.QueryImpl] - <executed in 0,16 s.
(/jcr:root/interedu/*)>
2007-11-19 14:33:14,329 DEBUG
[org.apache.jackrabbit.core.query.lucene.DocOrderNodeIteratorImpl] - <0
node(s) ordered in 0 ms>
2007-11-19 14:33:14,329 DEBUG [org.springmodules.jcr.JcrTemplate] - <Not
closing pre-bound Jcr Session after JcrTemplate>
2007-11-19 14:33:14,329 DEBUG
[org.springmodules.jcr.jackrabbit.LocalTransactionManager] - <Triggering
beforeCompletion synchronization>
2007-11-19 14:33:14,345 DEBUG
[org.springmodules.jcr.jackrabbit.LocalTransactionManager] - <Initiating
transaction rollback>
2007-11-19 14:33:14,345 DEBUG
[org.springmodules.jcr.jackrabbit.LocalTransactionManager] - <Rolling
back JCR transaction on session
[EMAIL PROTECTED]>
2007-11-19 14:33:14,345 DEBUG
[org.springmodules.jcr.jackrabbit.LocalTransactionManager] - <Triggering
afterCompletion synchronization>
2007-11-19 14:33:14,345 DEBUG
[org.springmodules.jcr.jackrabbit.LocalTransactionManager] - <Closing
JCR session [EMAIL PROTECTED] after
transaction>
2007-11-19 14:33:14,345 DEBUG
[org.springmodules.jcr.SessionFactoryUtils] - <Closing JCR Session>
2007-11-19 14:33:14,345 INFO
[org.apache.jackrabbit.core.TransientRepository] - <Session closed>
2007-11-19 14:33:14,345 DEBUG
[org.apache.jackrabbit.core.TransientRepository] - <Shutting down
transient repository>
[...]
2007-11-19 14:33:14,408 INFO
[org.apache.jackrabbit.core.TransientRepository] - <Transient repository
shut down>
Bye,
Daniel
