[Exist-development] Log file shrinkage and problems with database restarts....

[Andrzej J. T. <an...@ch...>]

More on this issue:

On the recovery I get the following exceptions in the exist log:

2010-02-01 20:40:01,993 [main] WARN  (RecoveryManager.java [doRecovery]:223) - Exception caught while redoing
transactions. Aborting recovery.
java.lang.ArrayIndexOutOfBoundsException
	at java.lang.System.arraycopy(Native Method)
	at org.exist.util.FixedByteArray.copyTo(FixedByteArray.java:41)
	at org.exist.storage.index.BFile.storeValueHelper(BFile.java:1353)
	at org.exist.storage.index.BFile.redoStoreValue(BFile.java:1044)
	at org.exist.storage.index.StoreValueLoggable.redo(StoreValueLoggable.java:94)
	at org.exist.storage.recovery.RecoveryManager.doRecovery(RecoveryManager.java:217)
	at org.exist.storage.recovery.RecoveryManager.recover(RecoveryManager.java:154)
	at org.exist.storage.txn.TransactionManager.runRecovery(TransactionManager.java:137)
	at org.exist.storage.BrokerPool.initialize(BrokerPool.java:793)
	at org.exist.storage.BrokerPool.<init>(BrokerPool.java:660)
	at org.exist.storage.BrokerPool.configure(BrokerPool.java:216)
	at org.exist.storage.BrokerPool.configure(BrokerPool.java:188)
	at org.exist.http.servlets.EXistServlet.startup(EXistServlet.java:675)
	at org.exist.http.servlets.EXistServlet.init(EXistServlet.java:116)
	at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1173)
	at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:993)
	at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:4149)
	at org.apache.catalina.core.StandardContext.start(StandardContext.java:4458)
	at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045)
	at org.apache.catalina.core.StandardHost.start(StandardHost.java:722)
	at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045)
	at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443)
	at org.apache.catalina.core.StandardService.start(StandardService.java:516)
	at org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
	at org.apache.catalina.startup.Catalina.start(Catalina.java:583)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
	at java.lang.reflect.Method.invoke(Unknown Source)
	at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:288)
	at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:413)
2010-02-01 20:40:02,009 [main] WARN  (RecoveryManager.java [doRecovery]:225) - Log entry that caused the exception: [0,
1389117] org.exist.storage.index.StoreValueLoggable  [BFile] - stored value with tid 1 on page 2
2010-02-01 20:40:02,009 [main] INFO  (RecoveryManager.java [doRecovery]:228) - Redo processed 89 out of 26721 transactions.


And then the database is unavailable.

Very frustrating, not being able to reload a clean database.

Any ideas what is causing this?

Are there some long running tasks that are not completing after my store of 25K documents? Or are there some shutdown
hooks that were put in Jetty, but aren't being executed in Tomcat?

Why would the recovery fail?  Then again, I'm puzzled why I'm even in recovery mode in the first place.

Help!

Thx!

-- 
Andrzej Taramina
Chaeron Corporation: Enterprise System Solutions
http://www.chaeron.com