Ron Todd - 2013-06-20

Hi RSSOwl Team,

I love RSSOwl when it works, but as my list of feeds and Saved Searches grows, the amount of time the program is "up" between crashes is shrinking.

The computer I have running RSSOwl did not itself crash nor was there a power-cut with RSSOwl open; so I know of no external reason for the database to have been corrupted. I believe there is a bug in the RSSOwl code that my usage of the product [many feeds (1844 now), several levels (5) of hierarchy organizing the feeds, many (100+) saved searches, each with a large number (sometimes 100+) of search conditions, several levels (3) of hierarchy organizing the saved searches, frequent updates (every 10 minutes), etc.] triggers. The RSSOwl database is about 60 to 120 MB, depending on how recently I have cleaned up.

A symptom I sometimes see that indicates that RSSOwl is about to crash is if I click on a folder of Saved Searches, I see no articles in the top pane of the Classic view window. Ordinarily, I would see the aggregate of all of the articles from every Saved Search that I have in that folder. I do see articles when I click on a Saved Search within the folder, as is expected. The log file snippet below shows some consternation within RSSOwl, starting at 13:34:23.117, while I click back and forth on folders of Saved Searches. Everything was working OK for the same toggling between Saved Search folders at 13:21:00.

I have a hunch that this may be related to the bug I observed associated with rapid updates: https://sourceforge.net/p/rssowl/discussion/296910/thread/4442cc1f/?limit=25#96cf, even though my update interval is now much longer than the update time (< 2 minutes). The crashes I see give me messages of RSSOwl "running out of memory" (I have been running with Xmx512M or Xmx1024M) or having a "java.lang.NullPointerException" internal error during: "" (there is nothing between the quotes). I can usually restart RSSOwl after these crashes, using the default database. However, sometimes when I run a cleanup and optimization, I then see the kind of crash ("RSSOwl is unable to start properly") that I reported in a crash report e-mail.

Is anyone working on resolving the queuing problem with update tasks (described in the link above)? Is a fix for this expected soon? That possible could fix this problem, too.

Is there further data that I can gather that will let the RSSSOwl team understand what is broken?

Best regards,
Ron

!SESSION 2013-06-19 09:21:11.986 -----------------------------------------------
eclipse.buildId=unknown
java.version=1.7.0_21
java.vendor=Oracle Corporation
BootLoader constants: OS=win32, ARCH=x86, WS=win32, NL=en_US
Command-line arguments: -os win32 -ws win32 -arch x86 -data C:\RSSOwl\Current\

!ENTRY org.rssowl.ui 1 0 2013-06-19 09:21:12.438
!MESSAGE RSSOwl Starting Up (RSSOwl/2.2.0.201305030754 (Windows; U; en))

!ENTRY org.rssowl.core 1 0 2013-06-19 09:22:08.957
!MESSAGE RSSOwl Shutting Down (normal)

!SESSION 2013-06-19 09:22:09.082 -----------------------------------------------
eclipse.buildId=unknown
java.version=1.7.0_21
java.vendor=Oracle Corporation
BootLoader constants: OS=win32, ARCH=x86, WS=win32, NL=en_US
Command-line arguments: -os win32 -ws win32 -arch x86 -data C:\RSSOwl\Current\

!ENTRY org.rssowl.ui 1 0 2013-06-19 09:22:09.503
!MESSAGE RSSOwl Starting Up (RSSOwl/2.2.0.201305030754 (Windows; U; en))

!ENTRY org.rssowl.core 1 0 2013-06-19 09:40:41.310
!MESSAGE RSSOwl Shutting Down (normal)

!SESSION 2013-06-19 09:40:41.466 -----------------------------------------------
eclipse.buildId=unknown
java.version=1.7.0_21
java.vendor=Oracle Corporation
BootLoader constants: OS=win32, ARCH=x86, WS=win32, NL=en_US
Command-line arguments: -os win32 -ws win32 -arch x86 -data C:\RSSOwl\Current\

!ENTRY org.rssowl.ui 1 0 2013-06-19 09:40:41.871
!MESSAGE RSSOwl Starting Up (RSSOwl/2.2.0.201305030754 (Windows; U; en))

!ENTRY org.rssowl.core 1 0 2013-06-19 09:40:42.121
!MESSAGE Start: Database Defragmentation

!ENTRY org.rssowl.core 1 0 2013-06-19 09:41:29.810
!MESSAGE Finished: Database Defragmentation

!ENTRY org.rssowl.core 1 0 2013-06-19 09:42:15.284
!MESSAGE RSSOwl Shutting Down (normal)

!SESSION 2013-06-19 11:03:17.500 -----------------------------------------------
eclipse.buildId=unknown
java.version=1.7.0_21
java.vendor=Oracle Corporation
BootLoader constants: OS=win32, ARCH=x86, WS=win32, NL=en_US
Command-line arguments: -os win32 -ws win32 -arch x86 -data C:\RSSOwl\Current\

!ENTRY org.rssowl.ui 1 0 2013-06-19 11:03:17.952
!MESSAGE RSSOwl Starting Up (RSSOwl/2.2.0.201305030754 (Windows; U; en))

!ENTRY org.rssowl.ui 4 2 2013-06-19 13:34:23.117
!MESSAGE Problems occurred when invoking code from plug-in: "org.rssowl.ui".
!STACK 0
org.rssowl.core.persist.service.PersistenceException: org.apache.lucene.store.LockReleaseFailedException: failed to delete C:\RSSOwl\Current.metadata.plugins\org.rssowl.core\lucene-10d2cef2ef4f47d45ec2cfb360caef73-n-write.lock
at org.rssowl.core.internal.persist.search.Indexer.dispose(Indexer.java:554)
at org.rssowl.core.internal.persist.search.Indexer.flushIfNecessary(Indexer.java:254)
at org.rssowl.core.internal.persist.search.ModelSearchImpl.getCurrentSearcher(ModelSearchImpl.java:489)
at org.rssowl.core.internal.persist.search.ModelSearchImpl.doSearchNews(ModelSearchImpl.java:434)
at org.rssowl.core.internal.persist.search.ModelSearchImpl.searchNews(ModelSearchImpl.java:410)
at org.rssowl.core.internal.persist.search.ModelSearchImpl.searchNews(ModelSearchImpl.java:400)
at org.rssowl.ui.internal.services.SavedSearchService.updateSavedSearches(SavedSearchService.java:273)
at org.rssowl.ui.internal.services.SavedSearchService.updateSavedSearches(SavedSearchService.java:249)
at org.rssowl.ui.internal.services.SavedSearchService.updateSavedSearches(SavedSearchService.java:241)
at org.rssowl.ui.internal.services.SavedSearchService$1$1.run(SavedSearchService.java:110)
at org.eclipse.core.runtime.SafeRunner.run(Unknown Source)
at org.rssowl.ui.internal.services.SavedSearchService$1.run(SavedSearchService.java:107)
at org.eclipse.core.internal.jobs.Worker.run(Unknown Source)
Caused by: org.apache.lucene.store.LockReleaseFailedException: failed to delete C:\RSSOwl\Current.metadata.plugins\org.rssowl.core\lucene-10d2cef2ef4f47d45ec2cfb360caef73-n-write.lock
at org.apache.lucene.store.NativeFSLock.release(NativeFSLockFactory.java:311)
at org.apache.lucene.index.IndexWriter.closeInternal(IndexWriter.java:1259)
at org.apache.lucene.index.IndexWriter.close(IndexWriter.java:1186)
at org.apache.lucene.index.IndexWriter.close(IndexWriter.java:1154)
at org.rssowl.core.internal.persist.search.Indexer.dispose(Indexer.java:552)
... 12 more

!ENTRY org.rssowl.core 4 2 2013-06-19 13:34:23.164
!MESSAGE Problems occurred when invoking code from plug-in: "org.rssowl.core".
!STACK 0
java.lang.NullPointerException
at org.apache.lucene.index.IndexWriter.closeInternal(IndexWriter.java:1208)
at org.apache.lucene.index.IndexWriter.close(IndexWriter.java:1186)
at org.apache.lucene.index.IndexWriter.close(IndexWriter.java:1154)
at org.rssowl.core.internal.persist.search.Indexer.dispose(Indexer.java:552)
at org.rssowl.core.internal.persist.search.Indexer.flushIfNecessary(Indexer.java:254)
at org.rssowl.core.internal.persist.search.ModelSearchImpl.getCurrentSearcher(ModelSearchImpl.java:489)
at org.rssowl.core.internal.persist.search.ModelSearchImpl.searchNewsByLinks(ModelSearchImpl.java:291)
at org.rssowl.core.internal.ApplicationServiceImpl.updateStateOfUnsavedNewNews(ApplicationServiceImpl.java:646)
at org.rssowl.core.internal.ApplicationServiceImpl.access$4(ApplicationServiceImpl.java:624)
at org.rssowl.core.internal.ApplicationServiceImpl$2.run(ApplicationServiceImpl.java:320)
at org.eclipse.core.runtime.SafeRunner.run(Unknown Source)
at org.rssowl.core.internal.ApplicationServiceImpl.handleFeedReload(ApplicationServiceImpl.java:317)
at org.rssowl.ui.internal.Controller$3.run(Controller.java:868)
at org.rssowl.core.util.JobQueue$2$1.run(JobQueue.java:329)
at org.eclipse.core.runtime.SafeRunner.run(Unknown Source)
at org.rssowl.core.util.JobQueue$2.run(JobQueue.java:326)
at org.eclipse.core.internal.jobs.Worker.run(Unknown Source)

!ENTRY org.rssowl.core 4 2 2013-06-19 13:34:23.164
!MESSAGE Problems occurred when invoking code from plug-in: "org.rssowl.core".
!STACK 0
java.lang.NullPointerException
at org.apache.lucene.index.IndexWriter.addDocument(IndexWriter.java:1464)
at org.apache.lucene.index.IndexWriter.addDocument(IndexWriter.java:1442)
at org.rssowl.core.internal.persist.search.Indexer.index(Indexer.java:190)
at org.rssowl.core.internal.persist.search.Indexer.index(Indexer.java:155)
at org.rssowl.core.internal.persist.search.IndexingTask.addToIndex(IndexingTask.java:182)
at org.rssowl.core.internal.persist.search.IndexingTask.run(IndexingTask.java:131)
at org.rssowl.core.util.JobQueue$2$1.run(JobQueue.java:329)
at org.eclipse.core.runtime.SafeRunner.run(Unknown Source)
at org.rssowl.core.util.JobQueue$2.run(JobQueue.java:326)
at org.eclipse.core.internal.jobs.Worker.run(Unknown Source)

///// 200+ "java.lang.NullPointerException" follow, and then:

!ENTRY org.rssowl.core 1 0 2013-06-19 13:45:54.118
!MESSAGE RSSOwl Shutting Down (normal)

!SESSION 2013-06-19 13:46:08.423 -----------------------------------------------
eclipse.buildId=unknown
java.version=1.7.0_21
java.vendor=Oracle Corporation
BootLoader constants: OS=win32, ARCH=x86, WS=win32, NL=en_US
Command-line arguments: -os win32 -ws win32 -arch x86 -data C:\RSSOwl\Current\

!ENTRY org.rssowl.ui 1 0 2013-06-19 13:46:08.844
!MESSAGE RSSOwl Starting Up (RSSOwl/2.2.0.201305030754 (Windows; U; en))

!ENTRY org.rssowl.core 1 0 2013-06-19 13:50:38.850
!MESSAGE RSSOwl Shutting Down (normal)

!SESSION 2013-06-19 13:50:39.411 -----------------------------------------------
eclipse.buildId=unknown
java.version=1.7.0_21
java.vendor=Oracle Corporation
BootLoader constants: OS=win32, ARCH=x86, WS=win32, NL=en_US
Command-line arguments: -os win32 -ws win32 -arch x86 -data C:\RSSOwl\Current\

!ENTRY org.rssowl.ui 1 0 2013-06-19 13:50:39.817
!MESSAGE RSSOwl Starting Up (RSSOwl/2.2.0.201305030754 (Windows; U; en))

!ENTRY org.rssowl.core 1 0 2013-06-19 13:50:40.067
!MESSAGE Start: Database Defragmentation

!ENTRY org.rssowl.core 1 0 2013-06-19 13:51:12.920
!MESSAGE Finished: Database Defragmentation

!ENTRY org.rssowl.core 1 0 2013-06-19 13:53:10.528
!MESSAGE RSSOwl Shutting Down (normal)