You are viewing a plain text version of this content. The canonical link for it is here.
Posted to users@jackrabbit.apache.org by Sergey Podatelev <br...@gmail.com> on 2009/02/20 02:48:50 UTC

Repository won't start after import

Hello,

I've tried to import a document-view XML of about 5 megabytes.
The import went fine, and I didn't get any errors during. Here's what I got
in stdout ("importing" is my own printout):

5973 [main] INFO org.apache.jackrabbit.core.TransientRepository - Transient
repository initialized
6004 [main] INFO org.apache.jackrabbit.core.TransientRepository - Session
opened
Importing...
17936 [main] INFO
org.apache.jackrabbit.core.persistence.bundle.util.LRUNodeIdCache -
num=4948/10240 hits=4943 miss=5057
18067 [main] INFO
org.apache.jackrabbit.core.persistence.bundle.util.LRUNodeIdCache -
num=4998/10240 hits=4998 miss=5002
86107 [IndexMerger] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger
- merged 743 documents in 1291 ms into _ph.
86897 [IndexMerger] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger
- merged 1000 documents in 449 ms into _pi.
87377 [IndexMerger] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger
- merged 1000 documents in 410 ms into _pj.
87787 [IndexMerger] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger
- merged 1000 documents in 365 ms into _pk.
88190 [IndexMerger] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger
- merged 1000 documents in 397 ms into _pl.
88495 [IndexMerger] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger
- merged 1000 documents in 285 ms into _pm.
88546 [main] INFO org.apache.jackrabbit.core.TransientRepository - Session
closed
88546 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - Shutting down
repository...
88558 [IndexMerger] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger
- IndexMerger terminated
88572 [main] INFO org.apache.jackrabbit.core.query.lucene.SearchIndex -
Index closed: /var/jackrabbit/repository//repository/index
88573 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - shutting down
workspace 'default'...
88573 [main] INFO
org.apache.jackrabbit.core.observation.ObservationDispatcher - Notification
of EventListeners stopped.
88727 [IndexMerger] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger
- merged 1000 documents in 209 ms into _pn.
88735 [IndexMerger] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger
- IndexMerger terminated
88922 [main] INFO org.apache.jackrabbit.core.query.lucene.SearchIndex -
Index closed: /var/jackrabbit/repository/workspaces/default/index
89958 [main] INFO
org.apache.jackrabbit.core.persistence.bundle.DerbyPersistenceManager -
Database '/var/jackrabbit/repository/workspaces/default/db' shutdown.
89959 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - workspace
'default' has been shutdown
89965 [main] INFO
org.apache.jackrabbit.core.persistence.bundle.DerbyPersistenceManager -
Database '/var/jackrabbit/repository//version/db' shutdown.
89966 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - Repository has
been shutdown
89966 [main] INFO org.apache.jackrabbit.core.TransientRepository - Transient
repository shut down

So, I don't see anything bad up there.
But then, when I try to start jackrabbit, I get this:

506 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - Starting
repository...
561 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
LocalFileSystem initialized at path /var/jackrabbit/repository/repository
799 [main] INFO org.apache.jackrabbit.core.nodetype.NodeTypeRegistry - no
custom node type definitions found
806 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
LocalFileSystem initialized at path /var/jackrabbit/repository/version
1736 [main] INFO
org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
- Database: Apache Derby / 10.4.1.3 - (648739)
1736 [main] INFO
org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
- Driver: Apache Derby Embedded JDBC Driver / 10.4.1.3 - (648739)
2524 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - initializing
workspace 'default'...
2524 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
LocalFileSystem initialized at path
/var/jackrabbit/repository/workspaces/default
2558 [main] INFO
org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
- Database: Apache Derby / 10.4.1.3 - (648739)
2558 [main] INFO
org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
- Driver: Apache Derby Embedded JDBC Driver / 10.4.1.3 - (648739)
2931 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - workspace
'default' initialized
3118 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
LocalFileSystem initialized at path
/var/jackrabbit/repository/repository/index
3229 [main] INFO org.apache.jackrabbit.core.query.lucene.SearchIndex - Index
initialized: /var/jackrabbit/repository//repository/index Version: 2
3248 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
LocalFileSystem initialized at path
/var/jackrabbit/repository/workspaces/default/index
4475 [main] ERROR org.apache.jackrabbit.core.RepositoryImpl - Failed to
initialize workspace 'default'
javax.jcr.RepositoryException: cannot read directory
org.apache.lucene.store.FSDirectory@/var/jackrabbit/repository/workspaces/default/index/_kn:
list() returned null: cannot read directory
org.apache.lucene.store.FSDirectory@/var/jackrabbit/repository/workspaces/default/index/_kn:
list() returned null: cannot read directory
org.apache.lucene.store.FSDirectory@/var/jackrabbit/repository/workspaces/default/index/_kn:
list() returned null

Caused by: java.io.FileNotFoundException: cannot read directory
org.apache.lucene.store.FSDirectory@/var/jackrabbit/repository/workspaces/default/index/_kn:
list() returned null
    at
org.apache.lucene.index.SegmentInfos$FindSegmentsFile.run(SegmentInfos.java:502)
    at org.apache.lucene.index.IndexReader.open(IndexReader.java:185)
    at org.apache.lucene.index.IndexReader.open(IndexReader.java:167)
    at
org.apache.jackrabbit.core.query.lucene.AbstractIndex.getReadOnlyIndexReader(AbstractIndex.java:273)
    at
org.apache.jackrabbit.core.query.lucene.MultiIndex.getIndexReader(MultiIndex.java:715)
    at
org.apache.jackrabbit.core.query.lucene.MultiIndex.<init>(MultiIndex.java:302)
    at
org.apache.jackrabbit.core.query.lucene.SearchIndex.doInit(SearchIndex.java:447)
    at
org.apache.jackrabbit.core.query.AbstractQueryHandler.init(AbstractQueryHandler.java:53)
    at
org.apache.jackrabbit.core.SearchManager.initializeQueryHandler(SearchManager.java:582)
    ... 13 more

java.io.FileNotFoundException: cannot read directory
org.apache.lucene.store.FSDirectory@/var/jackrabbit/repository/workspaces/default/index/_kn:
list() returned null
    at
org.apache.lucene.index.SegmentInfos$FindSegmentsFile.run(SegmentInfos.java:502)
    at org.apache.lucene.index.IndexReader.open(IndexReader.java:185)
    at org.apache.lucene.index.IndexReader.open(IndexReader.java:167)
    at
org.apache.jackrabbit.core.query.lucene.AbstractIndex.getReadOnlyIndexReader(AbstractIndex.java:273)
    at
org.apache.jackrabbit.core.query.lucene.MultiIndex.getIndexReader(MultiIndex.java:715)
    at
org.apache.jackrabbit.core.query.lucene.MultiIndex.<init>(MultiIndex.java:302)
    at
org.apache.jackrabbit.core.query.lucene.SearchIndex.doInit(SearchIndex.java:447)
    at
org.apache.jackrabbit.core.query.AbstractQueryHandler.init(AbstractQueryHandler.java:53)
    at
org.apache.jackrabbit.core.SearchManager.initializeQueryHandler(SearchManager.java:582)
    at
org.apache.jackrabbit.core.SearchManager.<init>(SearchManager.java:264)
    at
org.apache.jackrabbit.core.RepositoryImpl$WorkspaceInfo.getSearchManager(RepositoryImpl.java:1613)
    at
org.apache.jackrabbit.core.RepositoryImpl.initWorkspace(RepositoryImpl.java:606)
    at
org.apache.jackrabbit.core.RepositoryImpl.initStartupWorkspaces(RepositoryImpl.java:415)
    at
org.apache.jackrabbit.core.RepositoryImpl.<init>(RepositoryImpl.java:305)
    at
org.apache.jackrabbit.core.RepositoryImpl.create(RepositoryImpl.java:557)
    at
org.apache.jackrabbit.core.TransientRepository$2.getRepository(TransientRepository.java:245)
    at
org.apache.jackrabbit.core.TransientRepository.startRepository(TransientRepository.java:265)
    at
org.apache.jackrabbit.core.TransientRepository.login(TransientRepository.java:333)
    at
org.apache.jackrabbit.core.TransientRepository.login(TransientRepository.java:363)


I'm using Jackrabbit 1.4.5.

-- 
sp

Re: Repository won't start after import

Posted by Sergey Podatelev <br...@gmail.com>.
Just want to confirm my assumption: when I rolled back and tried to perform
same operations that gave me UUID-errors with repository DAO-s configured as
simple Spring beans, I don't see any of those errors. Perhaps, my DAOs are
configured improperly.

On Wed, Mar 4, 2009 at 3:06 PM, Sergey Podatelev <brightnesslevels@gmail.com
> wrote:

> I'm sorry for the delay, I had troubles reproducing this, until I got even
> worse issues.
>
> First off, I'm sorry for this stupid question, but how do I enable
> debug-level output?
> I have log4j.properties file in my /src folder with the following content:
>
> log4j.rootLogger=DEBUG, stdout
> log4j.appender.stdout=org.apache.log4j.ConsoleAppender
> log4j.appender.stdout.layout=org.apache.log4j.PatternLayout
> log4j.appender.stdout.layout.ConversionPattern=%-4r [%t] %-5p %c %x - %m%n
>
> However, I can only see INFO messages from the Jackrabbit's classes (my own
> classes emit some DEBUG-s as well, and I can see those).
>
> Secondly, I actually had a couple of "successful" imports, after which my
> web application started normally.
> However, what I'm getting now is somewhat similar to what I've found here:
> http://issues.apache.org/jira/browse/JCR-160
>
> Specifically, I get messages like "Exception retrieving Node with UUID:
> fabf6884-1457-4ccf-94f3-dde9d8319f6d: javax.jcr.ItemNotFoundException:
> fabf6884-1457-4ccf-94f3-dde9d8319f6d"
> from time to time though I didn't do any UUID lookups.
>
> And there're NodeIterator failures.
> Specifically, NodeIterator iter = q.execute().getNodes(); iter.getSize() --
> returns 1, but iter.hasNext() returns false.
>
> What scares me a little bit is that even if I delete
> "/var/jackrabbit/repository/workspaces/default/index" folder, those
> ghost-UUID errors still happen.
>
> Perhaps this is somehow related to the fact that before these UUID-errors I
> also configured webapp to work through Spring's LocalTransactionManager?
>
>
> On Wed, Feb 25, 2009 at 4:58 PM, Marcel Reutegger <
> marcel.reutegger@gmx.net> wrote:
>
>> Hi Sergey,
>>
>> On Mon, Feb 23, 2009 at 19:24, Sergey Podatelev
>> <br...@gmail.com> wrote:
>> > ...*repository initialized*
>> > ...*basic read: find a node, read it's name*
>> > ...*repository is shutting down*
>> > 5198 [main] INFO
>> > org.apache.jackrabbit.core.observation.ObservationDispatcher -
>> Notification
>> > of EventListeners stopped.
>> > 5698 [main] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger -
>> *Unable
>> > to stop IndexMerger. Deamon is busy.*
>> > 6009 [main] INFO org.apache.jackrabbit.core.query.lucene.SearchIndex -
>> Index
>> > closed: /var/jackrabbit/repository/workspaces/default/index
>> > 6022 [main] INFO
>> > org.apache.jackrabbit.core.persistence.bundle.DerbyPersistenceManager -
>> > Database '/var/jackrabbit/repository/workspaces/default/db' shutdown.*
>> >
>> > *Is this is somehow related to the index corruption?
>>
>> Yes, that could be the reason. I've opened a jira issue with the above
>> log information:
>> https://issues.apache.org/jira/browse/JCR-1999
>>
>> Can you please re-run your test with debug messages enabled for the
>> class IndexMerger and attach the log file to the jira issue? Thank
>> you.
>>
>> > Also, there's a typo in
>> > that warning, it's either "daemon" or "demon" (:
>>
>> Thanks for noticing, I'll correct it.
>>
>> regards
>>  marcel
>>
>
>
>
> --
> sp
>



-- 
sp

Re: Repository won't start after import

Posted by Marcel Reutegger <ma...@gmx.net>.
Hi,

On Wed, Mar 4, 2009 at 13:06, Sergey Podatelev
<br...@gmail.com> wrote:
> First off, I'm sorry for this stupid question, but how do I enable
> debug-level output?
> I have log4j.properties file in my /src folder with the following content:
>
> log4j.rootLogger=DEBUG, stdout
> log4j.appender.stdout=org.apache.log4j.ConsoleAppender
> log4j.appender.stdout.layout=org.apache.log4j.PatternLayout
> log4j.appender.stdout.layout.ConversionPattern=%-4r [%t] %-5p %c %x - %m%n

that looks good. jackrabbit uses slf4j for logging, which means you
also need the slf4j-log4j bridge that redirects slf4j log messages to
log4j.

regards
 marcel

Re: Repository won't start after import

Posted by Sergey Podatelev <br...@gmail.com>.
I'm sorry for the delay, I had troubles reproducing this, until I got even
worse issues.

First off, I'm sorry for this stupid question, but how do I enable
debug-level output?
I have log4j.properties file in my /src folder with the following content:

log4j.rootLogger=DEBUG, stdout
log4j.appender.stdout=org.apache.log4j.ConsoleAppender
log4j.appender.stdout.layout=org.apache.log4j.PatternLayout
log4j.appender.stdout.layout.ConversionPattern=%-4r [%t] %-5p %c %x - %m%n

However, I can only see INFO messages from the Jackrabbit's classes (my own
classes emit some DEBUG-s as well, and I can see those).

Secondly, I actually had a couple of "successful" imports, after which my
web application started normally.
However, what I'm getting now is somewhat similar to what I've found here:
http://issues.apache.org/jira/browse/JCR-160

Specifically, I get messages like "Exception retrieving Node with UUID:
fabf6884-1457-4ccf-94f3-dde9d8319f6d: javax.jcr.ItemNotFoundException:
fabf6884-1457-4ccf-94f3-dde9d8319f6d"
from time to time though I didn't do any UUID lookups.

And there're NodeIterator failures.
Specifically, NodeIterator iter = q.execute().getNodes(); iter.getSize() --
returns 1, but iter.hasNext() returns false.

What scares me a little bit is that even if I delete
"/var/jackrabbit/repository/workspaces/default/index" folder, those
ghost-UUID errors still happen.

Perhaps this is somehow related to the fact that before these UUID-errors I
also configured webapp to work through Spring's LocalTransactionManager?

On Wed, Feb 25, 2009 at 4:58 PM, Marcel Reutegger
<ma...@gmx.net>wrote:

> Hi Sergey,
>
> On Mon, Feb 23, 2009 at 19:24, Sergey Podatelev
> <br...@gmail.com> wrote:
> > ...*repository initialized*
> > ...*basic read: find a node, read it's name*
> > ...*repository is shutting down*
> > 5198 [main] INFO
> > org.apache.jackrabbit.core.observation.ObservationDispatcher -
> Notification
> > of EventListeners stopped.
> > 5698 [main] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger -
> *Unable
> > to stop IndexMerger. Deamon is busy.*
> > 6009 [main] INFO org.apache.jackrabbit.core.query.lucene.SearchIndex -
> Index
> > closed: /var/jackrabbit/repository/workspaces/default/index
> > 6022 [main] INFO
> > org.apache.jackrabbit.core.persistence.bundle.DerbyPersistenceManager -
> > Database '/var/jackrabbit/repository/workspaces/default/db' shutdown.*
> >
> > *Is this is somehow related to the index corruption?
>
> Yes, that could be the reason. I've opened a jira issue with the above
> log information:
> https://issues.apache.org/jira/browse/JCR-1999
>
> Can you please re-run your test with debug messages enabled for the
> class IndexMerger and attach the log file to the jira issue? Thank
> you.
>
> > Also, there's a typo in
> > that warning, it's either "daemon" or "demon" (:
>
> Thanks for noticing, I'll correct it.
>
> regards
>  marcel
>



-- 
sp

Re: Repository won't start after import

Posted by Marcel Reutegger <ma...@gmx.net>.
Hi Sergey,

On Mon, Feb 23, 2009 at 19:24, Sergey Podatelev
<br...@gmail.com> wrote:
> ...*repository initialized*
> ...*basic read: find a node, read it's name*
> ...*repository is shutting down*
> 5198 [main] INFO
> org.apache.jackrabbit.core.observation.ObservationDispatcher - Notification
> of EventListeners stopped.
> 5698 [main] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger - *Unable
> to stop IndexMerger. Deamon is busy.*
> 6009 [main] INFO org.apache.jackrabbit.core.query.lucene.SearchIndex - Index
> closed: /var/jackrabbit/repository/workspaces/default/index
> 6022 [main] INFO
> org.apache.jackrabbit.core.persistence.bundle.DerbyPersistenceManager -
> Database '/var/jackrabbit/repository/workspaces/default/db' shutdown.*
>
> *Is this is somehow related to the index corruption?

Yes, that could be the reason. I've opened a jira issue with the above
log information:
https://issues.apache.org/jira/browse/JCR-1999

Can you please re-run your test with debug messages enabled for the
class IndexMerger and attach the log file to the jira issue? Thank
you.

> Also, there's a typo in
> that warning, it's either "daemon" or "demon" (:

Thanks for noticing, I'll correct it.

regards
 marcel

Re: Repository won't start after import

Posted by Sergey Podatelev <br...@gmail.com>.
I'm not sure I am entitled to expose the actual content of the data i'm
trying to import, without which I assume there's no point to open a bug in
JIRA, I'll try to clear that.
So I'll try to proceed in this list by now, hope you guys will help.
I'm accessing the repo from two apps: a java web application and a
standalone java app. The webapp uses Spring and the repository is accessed
trough org.springmodules.jcr.jackrabbit.RepositoryFactoryBean. Standalone
application uses the setup almost identical to the one described in "first
hops". Among other things, standalone app has "import into repo" and "nudge
repo" options, first of which performs the aforementioned import, the latter
is just to check if the repo is okay.

Here's what I've noticed: after import is completed, when I try to start the
webapp, I get the exception described earlier.
But when I try to perform some basic reading with the standalone app, I get
this message:

...*repository initialized*
...*basic read: find a node, read it's name*
...*repository is shutting down*
5198 [main] INFO
org.apache.jackrabbit.core.observation.ObservationDispatcher - Notification
of EventListeners stopped.
5698 [main] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger - *Unable
to stop IndexMerger. Deamon is busy.*
6009 [main] INFO org.apache.jackrabbit.core.query.lucene.SearchIndex - Index
closed: /var/jackrabbit/repository/workspaces/default/index
6022 [main] INFO
org.apache.jackrabbit.core.persistence.bundle.DerbyPersistenceManager -
Database '/var/jackrabbit/repository/workspaces/default/db' shutdown.*

*Is this is somehow related to the index corruption? Also, there's a typo in
that warning, it's either "daemon" or "demon" (:


On Mon, Feb 23, 2009 at 11:29 AM, Marcel Reutegger <marcel.reutegger@gmx.net
> wrote:

> Hi Sergey,
>
> if you are able to reproduce this with a test, can you please file a
> JIRA issue? Thanks.
>
> regards
>  marcel
>
> 2009/2/22 Sergey Podatelev <br...@gmail.com>:
> > Thanks, Stefan, that did it.
> > However, I'm not happy that my index was corrupted.
> > Can someone please share why that might happen or how can I try to debug
> > this?
> > Looking at the default Jackrabbit's printouts during import I haven't
> found
> > anything criminal.
> >
> > On Fri, Feb 20, 2009 at 12:40 PM, Stefan Guggisberg <
> > stefan.guggisberg@gmail.com> wrote:
> >
> >> hi sergey,
> >>
> >> > org.apache.lucene.store.FSDirectory@
> >> /var/jackrabbit/repository/workspaces/default/index/_kn:
> >> > list() returned null
> >> >
> >> > Caused by: java.io.FileNotFoundException: cannot read directory
> >>
> >> seems like your index has somehow become corrupted.
> >>
> >> you can delete the search index folders
> >> (/var/jackrabbit/repository/workspaces/*/index)
> >> and try again. the index should be automatically rebuilt from scratch.
> >>
> >> cheers
> >> stefan
> >>
> >> On Fri, Feb 20, 2009 at 2:48 AM, Sergey Podatelev
> >> <br...@gmail.com> wrote:
> >> > Hello,
> >> >
> >> > I've tried to import a document-view XML of about 5 megabytes.
> >> > The import went fine, and I didn't get any errors during. Here's what
> I
> >> got
> >> > in stdout ("importing" is my own printout):
> >> >
> >> > 5973 [main] INFO org.apache.jackrabbit.core.TransientRepository -
> >> Transient
> >> > repository initialized
> >> > 6004 [main] INFO org.apache.jackrabbit.core.TransientRepository -
> Session
> >> > opened
> >> > Importing...
> >> > 17936 [main] INFO
> >> > org.apache.jackrabbit.core.persistence.bundle.util.LRUNodeIdCache -
> >> > num=4948/10240 hits=4943 miss=5057
> >> > 18067 [main] INFO
> >> > org.apache.jackrabbit.core.persistence.bundle.util.LRUNodeIdCache -
> >> > num=4998/10240 hits=4998 miss=5002
> >> > 86107 [IndexMerger] INFO
> >> org.apache.jackrabbit.core.query.lucene.IndexMerger
> >> > - merged 743 documents in 1291 ms into _ph.
> >> > 86897 [IndexMerger] INFO
> >> org.apache.jackrabbit.core.query.lucene.IndexMerger
> >> > - merged 1000 documents in 449 ms into _pi.
> >> > 87377 [IndexMerger] INFO
> >> org.apache.jackrabbit.core.query.lucene.IndexMerger
> >> > - merged 1000 documents in 410 ms into _pj.
> >> > 87787 [IndexMerger] INFO
> >> org.apache.jackrabbit.core.query.lucene.IndexMerger
> >> > - merged 1000 documents in 365 ms into _pk.
> >> > 88190 [IndexMerger] INFO
> >> org.apache.jackrabbit.core.query.lucene.IndexMerger
> >> > - merged 1000 documents in 397 ms into _pl.
> >> > 88495 [IndexMerger] INFO
> >> org.apache.jackrabbit.core.query.lucene.IndexMerger
> >> > - merged 1000 documents in 285 ms into _pm.
> >> > 88546 [main] INFO org.apache.jackrabbit.core.TransientRepository -
> >> Session
> >> > closed
> >> > 88546 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - Shutting
> >> down
> >> > repository...
> >> > 88558 [IndexMerger] INFO
> >> org.apache.jackrabbit.core.query.lucene.IndexMerger
> >> > - IndexMerger terminated
> >> > 88572 [main] INFO org.apache.jackrabbit.core.query.lucene.SearchIndex
> -
> >> > Index closed: /var/jackrabbit/repository//repository/index
> >> > 88573 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - shutting
> >> down
> >> > workspace 'default'...
> >> > 88573 [main] INFO
> >> > org.apache.jackrabbit.core.observation.ObservationDispatcher -
> >> Notification
> >> > of EventListeners stopped.
> >> > 88727 [IndexMerger] INFO
> >> org.apache.jackrabbit.core.query.lucene.IndexMerger
> >> > - merged 1000 documents in 209 ms into _pn.
> >> > 88735 [IndexMerger] INFO
> >> org.apache.jackrabbit.core.query.lucene.IndexMerger
> >> > - IndexMerger terminated
> >> > 88922 [main] INFO org.apache.jackrabbit.core.query.lucene.SearchIndex
> -
> >> > Index closed: /var/jackrabbit/repository/workspaces/default/index
> >> > 89958 [main] INFO
> >> > org.apache.jackrabbit.core.persistence.bundle.DerbyPersistenceManager
> -
> >> > Database '/var/jackrabbit/repository/workspaces/default/db' shutdown.
> >> > 89959 [main] INFO org.apache.jackrabbit.core.RepositoryImpl -
> workspace
> >> > 'default' has been shutdown
> >> > 89965 [main] INFO
> >> > org.apache.jackrabbit.core.persistence.bundle.DerbyPersistenceManager
> -
> >> > Database '/var/jackrabbit/repository//version/db' shutdown.
> >> > 89966 [main] INFO org.apache.jackrabbit.core.RepositoryImpl -
> Repository
> >> has
> >> > been shutdown
> >> > 89966 [main] INFO org.apache.jackrabbit.core.TransientRepository -
> >> Transient
> >> > repository shut down
> >> >
> >> > So, I don't see anything bad up there.
> >> > But then, when I try to start jackrabbit, I get this:
> >> >
> >> > 506 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - Starting
> >> > repository...
> >> > 561 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
> >> > LocalFileSystem initialized at path
> /var/jackrabbit/repository/repository
> >> > 799 [main] INFO org.apache.jackrabbit.core.nodetype.NodeTypeRegistry -
> no
> >> > custom node type definitions found
> >> > 806 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
> >> > LocalFileSystem initialized at path /var/jackrabbit/repository/version
> >> > 1736 [main] INFO
> >> >
> >>
> org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
> >> > - Database: Apache Derby / 10.4.1.3 - (648739)
> >> > 1736 [main] INFO
> >> >
> >>
> org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
> >> > - Driver: Apache Derby Embedded JDBC Driver / 10.4.1.3 - (648739)
> >> > 2524 [main] INFO org.apache.jackrabbit.core.RepositoryImpl -
> initializing
> >> > workspace 'default'...
> >> > 2524 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
> >> > LocalFileSystem initialized at path
> >> > /var/jackrabbit/repository/workspaces/default
> >> > 2558 [main] INFO
> >> >
> >>
> org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
> >> > - Database: Apache Derby / 10.4.1.3 - (648739)
> >> > 2558 [main] INFO
> >> >
> >>
> org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
> >> > - Driver: Apache Derby Embedded JDBC Driver / 10.4.1.3 - (648739)
> >> > 2931 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - workspace
> >> > 'default' initialized
> >> > 3118 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
> >> > LocalFileSystem initialized at path
> >> > /var/jackrabbit/repository/repository/index
> >> > 3229 [main] INFO org.apache.jackrabbit.core.query.lucene.SearchIndex -
> >> Index
> >> > initialized: /var/jackrabbit/repository//repository/index Version: 2
> >> > 3248 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
> >> > LocalFileSystem initialized at path
> >> > /var/jackrabbit/repository/workspaces/default/index
> >> > 4475 [main] ERROR org.apache.jackrabbit.core.RepositoryImpl - Failed
> to
> >> > initialize workspace 'default'
> >> > javax.jcr.RepositoryException: cannot read directory
> >> > org.apache.lucene.store.FSDirectory@
> >> /var/jackrabbit/repository/workspaces/default/index/_kn:
> >> > list() returned null: cannot read directory
> >> > org.apache.lucene.store.FSDirectory@
> >> /var/jackrabbit/repository/workspaces/default/index/_kn:
> >> > list() returned null: cannot read directory
> >> > org.apache.lucene.store.FSDirectory@
> >> /var/jackrabbit/repository/workspaces/default/index/_kn:
> >> > list() returned null
> >> >
> >> > Caused by: java.io.FileNotFoundException: cannot read directory
> >> > org.apache.lucene.store.FSDirectory@
> >> /var/jackrabbit/repository/workspaces/default/index/_kn:
> >> > list() returned null
> >> >    at
> >> >
> >>
> org.apache.lucene.index.SegmentInfos$FindSegmentsFile.run(SegmentInfos.java:502)
> >> >    at org.apache.lucene.index.IndexReader.open(IndexReader.java:185)
> >> >    at org.apache.lucene.index.IndexReader.open(IndexReader.java:167)
> >> >    at
> >> >
> >>
> org.apache.jackrabbit.core.query.lucene.AbstractIndex.getReadOnlyIndexReader(AbstractIndex.java:273)
> >> >    at
> >> >
> >>
> org.apache.jackrabbit.core.query.lucene.MultiIndex.getIndexReader(MultiIndex.java:715)
> >> >    at
> >> >
> >>
> org.apache.jackrabbit.core.query.lucene.MultiIndex.<init>(MultiIndex.java:302)
> >> >    at
> >> >
> >>
> org.apache.jackrabbit.core.query.lucene.SearchIndex.doInit(SearchIndex.java:447)
> >> >    at
> >> >
> >>
> org.apache.jackrabbit.core.query.AbstractQueryHandler.init(AbstractQueryHandler.java:53)
> >> >    at
> >> >
> >>
> org.apache.jackrabbit.core.SearchManager.initializeQueryHandler(SearchManager.java:582)
> >> >    ... 13 more
> >> >
> >> > java.io.FileNotFoundException: cannot read directory
> >> > org.apache.lucene.store.FSDirectory@
> >> /var/jackrabbit/repository/workspaces/default/index/_kn:
> >> > list() returned null
> >> >    at
> >> >
> >>
> org.apache.lucene.index.SegmentInfos$FindSegmentsFile.run(SegmentInfos.java:502)
> >> >    at org.apache.lucene.index.IndexReader.open(IndexReader.java:185)
> >> >    at org.apache.lucene.index.IndexReader.open(IndexReader.java:167)
> >> >    at
> >> >
> >>
> org.apache.jackrabbit.core.query.lucene.AbstractIndex.getReadOnlyIndexReader(AbstractIndex.java:273)
> >> >    at
> >> >
> >>
> org.apache.jackrabbit.core.query.lucene.MultiIndex.getIndexReader(MultiIndex.java:715)
> >> >    at
> >> >
> >>
> org.apache.jackrabbit.core.query.lucene.MultiIndex.<init>(MultiIndex.java:302)
> >> >    at
> >> >
> >>
> org.apache.jackrabbit.core.query.lucene.SearchIndex.doInit(SearchIndex.java:447)
> >> >    at
> >> >
> >>
> org.apache.jackrabbit.core.query.AbstractQueryHandler.init(AbstractQueryHandler.java:53)
> >> >    at
> >> >
> >>
> org.apache.jackrabbit.core.SearchManager.initializeQueryHandler(SearchManager.java:582)
> >> >    at
> >> >
> org.apache.jackrabbit.core.SearchManager.<init>(SearchManager.java:264)
> >> >    at
> >> >
> >>
> org.apache.jackrabbit.core.RepositoryImpl$WorkspaceInfo.getSearchManager(RepositoryImpl.java:1613)
> >> >    at
> >> >
> >>
> org.apache.jackrabbit.core.RepositoryImpl.initWorkspace(RepositoryImpl.java:606)
> >> >    at
> >> >
> >>
> org.apache.jackrabbit.core.RepositoryImpl.initStartupWorkspaces(RepositoryImpl.java:415)
> >> >    at
> >> >
> org.apache.jackrabbit.core.RepositoryImpl.<init>(RepositoryImpl.java:305)
> >> >    at
> >> >
> org.apache.jackrabbit.core.RepositoryImpl.create(RepositoryImpl.java:557)
> >> >    at
> >> >
> >>
> org.apache.jackrabbit.core.TransientRepository$2.getRepository(TransientRepository.java:245)
> >> >    at
> >> >
> >>
> org.apache.jackrabbit.core.TransientRepository.startRepository(TransientRepository.java:265)
> >> >    at
> >> >
> >>
> org.apache.jackrabbit.core.TransientRepository.login(TransientRepository.java:333)
> >> >    at
> >> >
> >>
> org.apache.jackrabbit.core.TransientRepository.login(TransientRepository.java:363)
> >> >
> >> >
> >> > I'm using Jackrabbit 1.4.5.
> >> >
> >> > --
> >> > sp
> >> >
> >>
> >
> >
> >
> > --
> > sp
> >
>



-- 
sp

Re: Repository won't start after import

Posted by Marcel Reutegger <ma...@gmx.net>.
Hi Sergey,

if you are able to reproduce this with a test, can you please file a
JIRA issue? Thanks.

regards
 marcel

2009/2/22 Sergey Podatelev <br...@gmail.com>:
> Thanks, Stefan, that did it.
> However, I'm not happy that my index was corrupted.
> Can someone please share why that might happen or how can I try to debug
> this?
> Looking at the default Jackrabbit's printouts during import I haven't found
> anything criminal.
>
> On Fri, Feb 20, 2009 at 12:40 PM, Stefan Guggisberg <
> stefan.guggisberg@gmail.com> wrote:
>
>> hi sergey,
>>
>> > org.apache.lucene.store.FSDirectory@
>> /var/jackrabbit/repository/workspaces/default/index/_kn:
>> > list() returned null
>> >
>> > Caused by: java.io.FileNotFoundException: cannot read directory
>>
>> seems like your index has somehow become corrupted.
>>
>> you can delete the search index folders
>> (/var/jackrabbit/repository/workspaces/*/index)
>> and try again. the index should be automatically rebuilt from scratch.
>>
>> cheers
>> stefan
>>
>> On Fri, Feb 20, 2009 at 2:48 AM, Sergey Podatelev
>> <br...@gmail.com> wrote:
>> > Hello,
>> >
>> > I've tried to import a document-view XML of about 5 megabytes.
>> > The import went fine, and I didn't get any errors during. Here's what I
>> got
>> > in stdout ("importing" is my own printout):
>> >
>> > 5973 [main] INFO org.apache.jackrabbit.core.TransientRepository -
>> Transient
>> > repository initialized
>> > 6004 [main] INFO org.apache.jackrabbit.core.TransientRepository - Session
>> > opened
>> > Importing...
>> > 17936 [main] INFO
>> > org.apache.jackrabbit.core.persistence.bundle.util.LRUNodeIdCache -
>> > num=4948/10240 hits=4943 miss=5057
>> > 18067 [main] INFO
>> > org.apache.jackrabbit.core.persistence.bundle.util.LRUNodeIdCache -
>> > num=4998/10240 hits=4998 miss=5002
>> > 86107 [IndexMerger] INFO
>> org.apache.jackrabbit.core.query.lucene.IndexMerger
>> > - merged 743 documents in 1291 ms into _ph.
>> > 86897 [IndexMerger] INFO
>> org.apache.jackrabbit.core.query.lucene.IndexMerger
>> > - merged 1000 documents in 449 ms into _pi.
>> > 87377 [IndexMerger] INFO
>> org.apache.jackrabbit.core.query.lucene.IndexMerger
>> > - merged 1000 documents in 410 ms into _pj.
>> > 87787 [IndexMerger] INFO
>> org.apache.jackrabbit.core.query.lucene.IndexMerger
>> > - merged 1000 documents in 365 ms into _pk.
>> > 88190 [IndexMerger] INFO
>> org.apache.jackrabbit.core.query.lucene.IndexMerger
>> > - merged 1000 documents in 397 ms into _pl.
>> > 88495 [IndexMerger] INFO
>> org.apache.jackrabbit.core.query.lucene.IndexMerger
>> > - merged 1000 documents in 285 ms into _pm.
>> > 88546 [main] INFO org.apache.jackrabbit.core.TransientRepository -
>> Session
>> > closed
>> > 88546 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - Shutting
>> down
>> > repository...
>> > 88558 [IndexMerger] INFO
>> org.apache.jackrabbit.core.query.lucene.IndexMerger
>> > - IndexMerger terminated
>> > 88572 [main] INFO org.apache.jackrabbit.core.query.lucene.SearchIndex -
>> > Index closed: /var/jackrabbit/repository//repository/index
>> > 88573 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - shutting
>> down
>> > workspace 'default'...
>> > 88573 [main] INFO
>> > org.apache.jackrabbit.core.observation.ObservationDispatcher -
>> Notification
>> > of EventListeners stopped.
>> > 88727 [IndexMerger] INFO
>> org.apache.jackrabbit.core.query.lucene.IndexMerger
>> > - merged 1000 documents in 209 ms into _pn.
>> > 88735 [IndexMerger] INFO
>> org.apache.jackrabbit.core.query.lucene.IndexMerger
>> > - IndexMerger terminated
>> > 88922 [main] INFO org.apache.jackrabbit.core.query.lucene.SearchIndex -
>> > Index closed: /var/jackrabbit/repository/workspaces/default/index
>> > 89958 [main] INFO
>> > org.apache.jackrabbit.core.persistence.bundle.DerbyPersistenceManager -
>> > Database '/var/jackrabbit/repository/workspaces/default/db' shutdown.
>> > 89959 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - workspace
>> > 'default' has been shutdown
>> > 89965 [main] INFO
>> > org.apache.jackrabbit.core.persistence.bundle.DerbyPersistenceManager -
>> > Database '/var/jackrabbit/repository//version/db' shutdown.
>> > 89966 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - Repository
>> has
>> > been shutdown
>> > 89966 [main] INFO org.apache.jackrabbit.core.TransientRepository -
>> Transient
>> > repository shut down
>> >
>> > So, I don't see anything bad up there.
>> > But then, when I try to start jackrabbit, I get this:
>> >
>> > 506 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - Starting
>> > repository...
>> > 561 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
>> > LocalFileSystem initialized at path /var/jackrabbit/repository/repository
>> > 799 [main] INFO org.apache.jackrabbit.core.nodetype.NodeTypeRegistry - no
>> > custom node type definitions found
>> > 806 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
>> > LocalFileSystem initialized at path /var/jackrabbit/repository/version
>> > 1736 [main] INFO
>> >
>> org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
>> > - Database: Apache Derby / 10.4.1.3 - (648739)
>> > 1736 [main] INFO
>> >
>> org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
>> > - Driver: Apache Derby Embedded JDBC Driver / 10.4.1.3 - (648739)
>> > 2524 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - initializing
>> > workspace 'default'...
>> > 2524 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
>> > LocalFileSystem initialized at path
>> > /var/jackrabbit/repository/workspaces/default
>> > 2558 [main] INFO
>> >
>> org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
>> > - Database: Apache Derby / 10.4.1.3 - (648739)
>> > 2558 [main] INFO
>> >
>> org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
>> > - Driver: Apache Derby Embedded JDBC Driver / 10.4.1.3 - (648739)
>> > 2931 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - workspace
>> > 'default' initialized
>> > 3118 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
>> > LocalFileSystem initialized at path
>> > /var/jackrabbit/repository/repository/index
>> > 3229 [main] INFO org.apache.jackrabbit.core.query.lucene.SearchIndex -
>> Index
>> > initialized: /var/jackrabbit/repository//repository/index Version: 2
>> > 3248 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
>> > LocalFileSystem initialized at path
>> > /var/jackrabbit/repository/workspaces/default/index
>> > 4475 [main] ERROR org.apache.jackrabbit.core.RepositoryImpl - Failed to
>> > initialize workspace 'default'
>> > javax.jcr.RepositoryException: cannot read directory
>> > org.apache.lucene.store.FSDirectory@
>> /var/jackrabbit/repository/workspaces/default/index/_kn:
>> > list() returned null: cannot read directory
>> > org.apache.lucene.store.FSDirectory@
>> /var/jackrabbit/repository/workspaces/default/index/_kn:
>> > list() returned null: cannot read directory
>> > org.apache.lucene.store.FSDirectory@
>> /var/jackrabbit/repository/workspaces/default/index/_kn:
>> > list() returned null
>> >
>> > Caused by: java.io.FileNotFoundException: cannot read directory
>> > org.apache.lucene.store.FSDirectory@
>> /var/jackrabbit/repository/workspaces/default/index/_kn:
>> > list() returned null
>> >    at
>> >
>> org.apache.lucene.index.SegmentInfos$FindSegmentsFile.run(SegmentInfos.java:502)
>> >    at org.apache.lucene.index.IndexReader.open(IndexReader.java:185)
>> >    at org.apache.lucene.index.IndexReader.open(IndexReader.java:167)
>> >    at
>> >
>> org.apache.jackrabbit.core.query.lucene.AbstractIndex.getReadOnlyIndexReader(AbstractIndex.java:273)
>> >    at
>> >
>> org.apache.jackrabbit.core.query.lucene.MultiIndex.getIndexReader(MultiIndex.java:715)
>> >    at
>> >
>> org.apache.jackrabbit.core.query.lucene.MultiIndex.<init>(MultiIndex.java:302)
>> >    at
>> >
>> org.apache.jackrabbit.core.query.lucene.SearchIndex.doInit(SearchIndex.java:447)
>> >    at
>> >
>> org.apache.jackrabbit.core.query.AbstractQueryHandler.init(AbstractQueryHandler.java:53)
>> >    at
>> >
>> org.apache.jackrabbit.core.SearchManager.initializeQueryHandler(SearchManager.java:582)
>> >    ... 13 more
>> >
>> > java.io.FileNotFoundException: cannot read directory
>> > org.apache.lucene.store.FSDirectory@
>> /var/jackrabbit/repository/workspaces/default/index/_kn:
>> > list() returned null
>> >    at
>> >
>> org.apache.lucene.index.SegmentInfos$FindSegmentsFile.run(SegmentInfos.java:502)
>> >    at org.apache.lucene.index.IndexReader.open(IndexReader.java:185)
>> >    at org.apache.lucene.index.IndexReader.open(IndexReader.java:167)
>> >    at
>> >
>> org.apache.jackrabbit.core.query.lucene.AbstractIndex.getReadOnlyIndexReader(AbstractIndex.java:273)
>> >    at
>> >
>> org.apache.jackrabbit.core.query.lucene.MultiIndex.getIndexReader(MultiIndex.java:715)
>> >    at
>> >
>> org.apache.jackrabbit.core.query.lucene.MultiIndex.<init>(MultiIndex.java:302)
>> >    at
>> >
>> org.apache.jackrabbit.core.query.lucene.SearchIndex.doInit(SearchIndex.java:447)
>> >    at
>> >
>> org.apache.jackrabbit.core.query.AbstractQueryHandler.init(AbstractQueryHandler.java:53)
>> >    at
>> >
>> org.apache.jackrabbit.core.SearchManager.initializeQueryHandler(SearchManager.java:582)
>> >    at
>> > org.apache.jackrabbit.core.SearchManager.<init>(SearchManager.java:264)
>> >    at
>> >
>> org.apache.jackrabbit.core.RepositoryImpl$WorkspaceInfo.getSearchManager(RepositoryImpl.java:1613)
>> >    at
>> >
>> org.apache.jackrabbit.core.RepositoryImpl.initWorkspace(RepositoryImpl.java:606)
>> >    at
>> >
>> org.apache.jackrabbit.core.RepositoryImpl.initStartupWorkspaces(RepositoryImpl.java:415)
>> >    at
>> > org.apache.jackrabbit.core.RepositoryImpl.<init>(RepositoryImpl.java:305)
>> >    at
>> > org.apache.jackrabbit.core.RepositoryImpl.create(RepositoryImpl.java:557)
>> >    at
>> >
>> org.apache.jackrabbit.core.TransientRepository$2.getRepository(TransientRepository.java:245)
>> >    at
>> >
>> org.apache.jackrabbit.core.TransientRepository.startRepository(TransientRepository.java:265)
>> >    at
>> >
>> org.apache.jackrabbit.core.TransientRepository.login(TransientRepository.java:333)
>> >    at
>> >
>> org.apache.jackrabbit.core.TransientRepository.login(TransientRepository.java:363)
>> >
>> >
>> > I'm using Jackrabbit 1.4.5.
>> >
>> > --
>> > sp
>> >
>>
>
>
>
> --
> sp
>

Re: Repository won't start after import

Posted by Sergey Podatelev <br...@gmail.com>.
Thanks, Stefan, that did it.
However, I'm not happy that my index was corrupted.
Can someone please share why that might happen or how can I try to debug
this?
Looking at the default Jackrabbit's printouts during import I haven't found
anything criminal.

On Fri, Feb 20, 2009 at 12:40 PM, Stefan Guggisberg <
stefan.guggisberg@gmail.com> wrote:

> hi sergey,
>
> > org.apache.lucene.store.FSDirectory@
> /var/jackrabbit/repository/workspaces/default/index/_kn:
> > list() returned null
> >
> > Caused by: java.io.FileNotFoundException: cannot read directory
>
> seems like your index has somehow become corrupted.
>
> you can delete the search index folders
> (/var/jackrabbit/repository/workspaces/*/index)
> and try again. the index should be automatically rebuilt from scratch.
>
> cheers
> stefan
>
> On Fri, Feb 20, 2009 at 2:48 AM, Sergey Podatelev
> <br...@gmail.com> wrote:
> > Hello,
> >
> > I've tried to import a document-view XML of about 5 megabytes.
> > The import went fine, and I didn't get any errors during. Here's what I
> got
> > in stdout ("importing" is my own printout):
> >
> > 5973 [main] INFO org.apache.jackrabbit.core.TransientRepository -
> Transient
> > repository initialized
> > 6004 [main] INFO org.apache.jackrabbit.core.TransientRepository - Session
> > opened
> > Importing...
> > 17936 [main] INFO
> > org.apache.jackrabbit.core.persistence.bundle.util.LRUNodeIdCache -
> > num=4948/10240 hits=4943 miss=5057
> > 18067 [main] INFO
> > org.apache.jackrabbit.core.persistence.bundle.util.LRUNodeIdCache -
> > num=4998/10240 hits=4998 miss=5002
> > 86107 [IndexMerger] INFO
> org.apache.jackrabbit.core.query.lucene.IndexMerger
> > - merged 743 documents in 1291 ms into _ph.
> > 86897 [IndexMerger] INFO
> org.apache.jackrabbit.core.query.lucene.IndexMerger
> > - merged 1000 documents in 449 ms into _pi.
> > 87377 [IndexMerger] INFO
> org.apache.jackrabbit.core.query.lucene.IndexMerger
> > - merged 1000 documents in 410 ms into _pj.
> > 87787 [IndexMerger] INFO
> org.apache.jackrabbit.core.query.lucene.IndexMerger
> > - merged 1000 documents in 365 ms into _pk.
> > 88190 [IndexMerger] INFO
> org.apache.jackrabbit.core.query.lucene.IndexMerger
> > - merged 1000 documents in 397 ms into _pl.
> > 88495 [IndexMerger] INFO
> org.apache.jackrabbit.core.query.lucene.IndexMerger
> > - merged 1000 documents in 285 ms into _pm.
> > 88546 [main] INFO org.apache.jackrabbit.core.TransientRepository -
> Session
> > closed
> > 88546 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - Shutting
> down
> > repository...
> > 88558 [IndexMerger] INFO
> org.apache.jackrabbit.core.query.lucene.IndexMerger
> > - IndexMerger terminated
> > 88572 [main] INFO org.apache.jackrabbit.core.query.lucene.SearchIndex -
> > Index closed: /var/jackrabbit/repository//repository/index
> > 88573 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - shutting
> down
> > workspace 'default'...
> > 88573 [main] INFO
> > org.apache.jackrabbit.core.observation.ObservationDispatcher -
> Notification
> > of EventListeners stopped.
> > 88727 [IndexMerger] INFO
> org.apache.jackrabbit.core.query.lucene.IndexMerger
> > - merged 1000 documents in 209 ms into _pn.
> > 88735 [IndexMerger] INFO
> org.apache.jackrabbit.core.query.lucene.IndexMerger
> > - IndexMerger terminated
> > 88922 [main] INFO org.apache.jackrabbit.core.query.lucene.SearchIndex -
> > Index closed: /var/jackrabbit/repository/workspaces/default/index
> > 89958 [main] INFO
> > org.apache.jackrabbit.core.persistence.bundle.DerbyPersistenceManager -
> > Database '/var/jackrabbit/repository/workspaces/default/db' shutdown.
> > 89959 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - workspace
> > 'default' has been shutdown
> > 89965 [main] INFO
> > org.apache.jackrabbit.core.persistence.bundle.DerbyPersistenceManager -
> > Database '/var/jackrabbit/repository//version/db' shutdown.
> > 89966 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - Repository
> has
> > been shutdown
> > 89966 [main] INFO org.apache.jackrabbit.core.TransientRepository -
> Transient
> > repository shut down
> >
> > So, I don't see anything bad up there.
> > But then, when I try to start jackrabbit, I get this:
> >
> > 506 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - Starting
> > repository...
> > 561 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
> > LocalFileSystem initialized at path /var/jackrabbit/repository/repository
> > 799 [main] INFO org.apache.jackrabbit.core.nodetype.NodeTypeRegistry - no
> > custom node type definitions found
> > 806 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
> > LocalFileSystem initialized at path /var/jackrabbit/repository/version
> > 1736 [main] INFO
> >
> org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
> > - Database: Apache Derby / 10.4.1.3 - (648739)
> > 1736 [main] INFO
> >
> org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
> > - Driver: Apache Derby Embedded JDBC Driver / 10.4.1.3 - (648739)
> > 2524 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - initializing
> > workspace 'default'...
> > 2524 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
> > LocalFileSystem initialized at path
> > /var/jackrabbit/repository/workspaces/default
> > 2558 [main] INFO
> >
> org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
> > - Database: Apache Derby / 10.4.1.3 - (648739)
> > 2558 [main] INFO
> >
> org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
> > - Driver: Apache Derby Embedded JDBC Driver / 10.4.1.3 - (648739)
> > 2931 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - workspace
> > 'default' initialized
> > 3118 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
> > LocalFileSystem initialized at path
> > /var/jackrabbit/repository/repository/index
> > 3229 [main] INFO org.apache.jackrabbit.core.query.lucene.SearchIndex -
> Index
> > initialized: /var/jackrabbit/repository//repository/index Version: 2
> > 3248 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
> > LocalFileSystem initialized at path
> > /var/jackrabbit/repository/workspaces/default/index
> > 4475 [main] ERROR org.apache.jackrabbit.core.RepositoryImpl - Failed to
> > initialize workspace 'default'
> > javax.jcr.RepositoryException: cannot read directory
> > org.apache.lucene.store.FSDirectory@
> /var/jackrabbit/repository/workspaces/default/index/_kn:
> > list() returned null: cannot read directory
> > org.apache.lucene.store.FSDirectory@
> /var/jackrabbit/repository/workspaces/default/index/_kn:
> > list() returned null: cannot read directory
> > org.apache.lucene.store.FSDirectory@
> /var/jackrabbit/repository/workspaces/default/index/_kn:
> > list() returned null
> >
> > Caused by: java.io.FileNotFoundException: cannot read directory
> > org.apache.lucene.store.FSDirectory@
> /var/jackrabbit/repository/workspaces/default/index/_kn:
> > list() returned null
> >    at
> >
> org.apache.lucene.index.SegmentInfos$FindSegmentsFile.run(SegmentInfos.java:502)
> >    at org.apache.lucene.index.IndexReader.open(IndexReader.java:185)
> >    at org.apache.lucene.index.IndexReader.open(IndexReader.java:167)
> >    at
> >
> org.apache.jackrabbit.core.query.lucene.AbstractIndex.getReadOnlyIndexReader(AbstractIndex.java:273)
> >    at
> >
> org.apache.jackrabbit.core.query.lucene.MultiIndex.getIndexReader(MultiIndex.java:715)
> >    at
> >
> org.apache.jackrabbit.core.query.lucene.MultiIndex.<init>(MultiIndex.java:302)
> >    at
> >
> org.apache.jackrabbit.core.query.lucene.SearchIndex.doInit(SearchIndex.java:447)
> >    at
> >
> org.apache.jackrabbit.core.query.AbstractQueryHandler.init(AbstractQueryHandler.java:53)
> >    at
> >
> org.apache.jackrabbit.core.SearchManager.initializeQueryHandler(SearchManager.java:582)
> >    ... 13 more
> >
> > java.io.FileNotFoundException: cannot read directory
> > org.apache.lucene.store.FSDirectory@
> /var/jackrabbit/repository/workspaces/default/index/_kn:
> > list() returned null
> >    at
> >
> org.apache.lucene.index.SegmentInfos$FindSegmentsFile.run(SegmentInfos.java:502)
> >    at org.apache.lucene.index.IndexReader.open(IndexReader.java:185)
> >    at org.apache.lucene.index.IndexReader.open(IndexReader.java:167)
> >    at
> >
> org.apache.jackrabbit.core.query.lucene.AbstractIndex.getReadOnlyIndexReader(AbstractIndex.java:273)
> >    at
> >
> org.apache.jackrabbit.core.query.lucene.MultiIndex.getIndexReader(MultiIndex.java:715)
> >    at
> >
> org.apache.jackrabbit.core.query.lucene.MultiIndex.<init>(MultiIndex.java:302)
> >    at
> >
> org.apache.jackrabbit.core.query.lucene.SearchIndex.doInit(SearchIndex.java:447)
> >    at
> >
> org.apache.jackrabbit.core.query.AbstractQueryHandler.init(AbstractQueryHandler.java:53)
> >    at
> >
> org.apache.jackrabbit.core.SearchManager.initializeQueryHandler(SearchManager.java:582)
> >    at
> > org.apache.jackrabbit.core.SearchManager.<init>(SearchManager.java:264)
> >    at
> >
> org.apache.jackrabbit.core.RepositoryImpl$WorkspaceInfo.getSearchManager(RepositoryImpl.java:1613)
> >    at
> >
> org.apache.jackrabbit.core.RepositoryImpl.initWorkspace(RepositoryImpl.java:606)
> >    at
> >
> org.apache.jackrabbit.core.RepositoryImpl.initStartupWorkspaces(RepositoryImpl.java:415)
> >    at
> > org.apache.jackrabbit.core.RepositoryImpl.<init>(RepositoryImpl.java:305)
> >    at
> > org.apache.jackrabbit.core.RepositoryImpl.create(RepositoryImpl.java:557)
> >    at
> >
> org.apache.jackrabbit.core.TransientRepository$2.getRepository(TransientRepository.java:245)
> >    at
> >
> org.apache.jackrabbit.core.TransientRepository.startRepository(TransientRepository.java:265)
> >    at
> >
> org.apache.jackrabbit.core.TransientRepository.login(TransientRepository.java:333)
> >    at
> >
> org.apache.jackrabbit.core.TransientRepository.login(TransientRepository.java:363)
> >
> >
> > I'm using Jackrabbit 1.4.5.
> >
> > --
> > sp
> >
>



-- 
sp

Re: Repository won't start after import

Posted by Stefan Guggisberg <st...@gmail.com>.
hi sergey,

> org.apache.lucene.store.FSDirectory@/var/jackrabbit/repository/workspaces/default/index/_kn:
> list() returned null
>
> Caused by: java.io.FileNotFoundException: cannot read directory

seems like your index has somehow become corrupted.

you can delete the search index folders
(/var/jackrabbit/repository/workspaces/*/index)
and try again. the index should be automatically rebuilt from scratch.

cheers
stefan

On Fri, Feb 20, 2009 at 2:48 AM, Sergey Podatelev
<br...@gmail.com> wrote:
> Hello,
>
> I've tried to import a document-view XML of about 5 megabytes.
> The import went fine, and I didn't get any errors during. Here's what I got
> in stdout ("importing" is my own printout):
>
> 5973 [main] INFO org.apache.jackrabbit.core.TransientRepository - Transient
> repository initialized
> 6004 [main] INFO org.apache.jackrabbit.core.TransientRepository - Session
> opened
> Importing...
> 17936 [main] INFO
> org.apache.jackrabbit.core.persistence.bundle.util.LRUNodeIdCache -
> num=4948/10240 hits=4943 miss=5057
> 18067 [main] INFO
> org.apache.jackrabbit.core.persistence.bundle.util.LRUNodeIdCache -
> num=4998/10240 hits=4998 miss=5002
> 86107 [IndexMerger] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger
> - merged 743 documents in 1291 ms into _ph.
> 86897 [IndexMerger] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger
> - merged 1000 documents in 449 ms into _pi.
> 87377 [IndexMerger] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger
> - merged 1000 documents in 410 ms into _pj.
> 87787 [IndexMerger] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger
> - merged 1000 documents in 365 ms into _pk.
> 88190 [IndexMerger] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger
> - merged 1000 documents in 397 ms into _pl.
> 88495 [IndexMerger] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger
> - merged 1000 documents in 285 ms into _pm.
> 88546 [main] INFO org.apache.jackrabbit.core.TransientRepository - Session
> closed
> 88546 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - Shutting down
> repository...
> 88558 [IndexMerger] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger
> - IndexMerger terminated
> 88572 [main] INFO org.apache.jackrabbit.core.query.lucene.SearchIndex -
> Index closed: /var/jackrabbit/repository//repository/index
> 88573 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - shutting down
> workspace 'default'...
> 88573 [main] INFO
> org.apache.jackrabbit.core.observation.ObservationDispatcher - Notification
> of EventListeners stopped.
> 88727 [IndexMerger] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger
> - merged 1000 documents in 209 ms into _pn.
> 88735 [IndexMerger] INFO org.apache.jackrabbit.core.query.lucene.IndexMerger
> - IndexMerger terminated
> 88922 [main] INFO org.apache.jackrabbit.core.query.lucene.SearchIndex -
> Index closed: /var/jackrabbit/repository/workspaces/default/index
> 89958 [main] INFO
> org.apache.jackrabbit.core.persistence.bundle.DerbyPersistenceManager -
> Database '/var/jackrabbit/repository/workspaces/default/db' shutdown.
> 89959 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - workspace
> 'default' has been shutdown
> 89965 [main] INFO
> org.apache.jackrabbit.core.persistence.bundle.DerbyPersistenceManager -
> Database '/var/jackrabbit/repository//version/db' shutdown.
> 89966 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - Repository has
> been shutdown
> 89966 [main] INFO org.apache.jackrabbit.core.TransientRepository - Transient
> repository shut down
>
> So, I don't see anything bad up there.
> But then, when I try to start jackrabbit, I get this:
>
> 506 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - Starting
> repository...
> 561 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
> LocalFileSystem initialized at path /var/jackrabbit/repository/repository
> 799 [main] INFO org.apache.jackrabbit.core.nodetype.NodeTypeRegistry - no
> custom node type definitions found
> 806 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
> LocalFileSystem initialized at path /var/jackrabbit/repository/version
> 1736 [main] INFO
> org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
> - Database: Apache Derby / 10.4.1.3 - (648739)
> 1736 [main] INFO
> org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
> - Driver: Apache Derby Embedded JDBC Driver / 10.4.1.3 - (648739)
> 2524 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - initializing
> workspace 'default'...
> 2524 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
> LocalFileSystem initialized at path
> /var/jackrabbit/repository/workspaces/default
> 2558 [main] INFO
> org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
> - Database: Apache Derby / 10.4.1.3 - (648739)
> 2558 [main] INFO
> org.apache.jackrabbit.core.persistence.bundle.util.ConnectionRecoveryManager
> - Driver: Apache Derby Embedded JDBC Driver / 10.4.1.3 - (648739)
> 2931 [main] INFO org.apache.jackrabbit.core.RepositoryImpl - workspace
> 'default' initialized
> 3118 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
> LocalFileSystem initialized at path
> /var/jackrabbit/repository/repository/index
> 3229 [main] INFO org.apache.jackrabbit.core.query.lucene.SearchIndex - Index
> initialized: /var/jackrabbit/repository//repository/index Version: 2
> 3248 [main] INFO org.apache.jackrabbit.core.fs.local.LocalFileSystem -
> LocalFileSystem initialized at path
> /var/jackrabbit/repository/workspaces/default/index
> 4475 [main] ERROR org.apache.jackrabbit.core.RepositoryImpl - Failed to
> initialize workspace 'default'
> javax.jcr.RepositoryException: cannot read directory
> org.apache.lucene.store.FSDirectory@/var/jackrabbit/repository/workspaces/default/index/_kn:
> list() returned null: cannot read directory
> org.apache.lucene.store.FSDirectory@/var/jackrabbit/repository/workspaces/default/index/_kn:
> list() returned null: cannot read directory
> org.apache.lucene.store.FSDirectory@/var/jackrabbit/repository/workspaces/default/index/_kn:
> list() returned null
>
> Caused by: java.io.FileNotFoundException: cannot read directory
> org.apache.lucene.store.FSDirectory@/var/jackrabbit/repository/workspaces/default/index/_kn:
> list() returned null
>    at
> org.apache.lucene.index.SegmentInfos$FindSegmentsFile.run(SegmentInfos.java:502)
>    at org.apache.lucene.index.IndexReader.open(IndexReader.java:185)
>    at org.apache.lucene.index.IndexReader.open(IndexReader.java:167)
>    at
> org.apache.jackrabbit.core.query.lucene.AbstractIndex.getReadOnlyIndexReader(AbstractIndex.java:273)
>    at
> org.apache.jackrabbit.core.query.lucene.MultiIndex.getIndexReader(MultiIndex.java:715)
>    at
> org.apache.jackrabbit.core.query.lucene.MultiIndex.<init>(MultiIndex.java:302)
>    at
> org.apache.jackrabbit.core.query.lucene.SearchIndex.doInit(SearchIndex.java:447)
>    at
> org.apache.jackrabbit.core.query.AbstractQueryHandler.init(AbstractQueryHandler.java:53)
>    at
> org.apache.jackrabbit.core.SearchManager.initializeQueryHandler(SearchManager.java:582)
>    ... 13 more
>
> java.io.FileNotFoundException: cannot read directory
> org.apache.lucene.store.FSDirectory@/var/jackrabbit/repository/workspaces/default/index/_kn:
> list() returned null
>    at
> org.apache.lucene.index.SegmentInfos$FindSegmentsFile.run(SegmentInfos.java:502)
>    at org.apache.lucene.index.IndexReader.open(IndexReader.java:185)
>    at org.apache.lucene.index.IndexReader.open(IndexReader.java:167)
>    at
> org.apache.jackrabbit.core.query.lucene.AbstractIndex.getReadOnlyIndexReader(AbstractIndex.java:273)
>    at
> org.apache.jackrabbit.core.query.lucene.MultiIndex.getIndexReader(MultiIndex.java:715)
>    at
> org.apache.jackrabbit.core.query.lucene.MultiIndex.<init>(MultiIndex.java:302)
>    at
> org.apache.jackrabbit.core.query.lucene.SearchIndex.doInit(SearchIndex.java:447)
>    at
> org.apache.jackrabbit.core.query.AbstractQueryHandler.init(AbstractQueryHandler.java:53)
>    at
> org.apache.jackrabbit.core.SearchManager.initializeQueryHandler(SearchManager.java:582)
>    at
> org.apache.jackrabbit.core.SearchManager.<init>(SearchManager.java:264)
>    at
> org.apache.jackrabbit.core.RepositoryImpl$WorkspaceInfo.getSearchManager(RepositoryImpl.java:1613)
>    at
> org.apache.jackrabbit.core.RepositoryImpl.initWorkspace(RepositoryImpl.java:606)
>    at
> org.apache.jackrabbit.core.RepositoryImpl.initStartupWorkspaces(RepositoryImpl.java:415)
>    at
> org.apache.jackrabbit.core.RepositoryImpl.<init>(RepositoryImpl.java:305)
>    at
> org.apache.jackrabbit.core.RepositoryImpl.create(RepositoryImpl.java:557)
>    at
> org.apache.jackrabbit.core.TransientRepository$2.getRepository(TransientRepository.java:245)
>    at
> org.apache.jackrabbit.core.TransientRepository.startRepository(TransientRepository.java:265)
>    at
> org.apache.jackrabbit.core.TransientRepository.login(TransientRepository.java:333)
>    at
> org.apache.jackrabbit.core.TransientRepository.login(TransientRepository.java:363)
>
>
> I'm using Jackrabbit 1.4.5.
>
> --
> sp
>