You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@jackrabbit.apache.org by "Micah Whitacre (JIRA)" <ji...@apache.org> on 2008/04/23 17:15:25 UTC
[jira] Created: (JCR-1553) ClusterNode not properly shutdown when
repository has shutdown
ClusterNode not properly shutdown when repository has shutdown
--------------------------------------------------------------
Key: JCR-1553
URL: https://issues.apache.org/jira/browse/JCR-1553
Project: Jackrabbit
Issue Type: Bug
Components: clustering, jackrabbit-core
Affects Versions: core 1.4.1
Environment: windows vista jdk 1.5.0_11.
Reporter: Micah Whitacre
Sometimes when the repository is shutdown the ClusterNode is not shutdown and it therefore tries to update records or access a closed Journal file. The setup that generated the exception is I have 3 VMs each with a Repository that are all connected to the same database. In the below stack trace one of the repositories is being shutdown however the ClusterNode thread is also trying to update the repository journal at the same time. Below is a copy of the stack trace.
[4/23/08 9:58:52:496 CDT] 00000061 SystemOut O 89811653 [WebContainer : 2] INFO org.apache.jackrabbit.core.RepositoryImpl - Shutting down repository...
[4/23/08 9:58:52:511 CDT] 0000054c SystemOut O 89811621 [ClusterNode-b06e4fe7-a602-4a93-b106-e0834046ae0f] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7174
[4/23/08 9:58:52:527 CDT] 00000061 SystemOut O 89811684 [WebContainer : 2] INFO org.apache.jackrabbit.core.RepositoryImpl - shutting down workspace 'default'...
[4/23/08 9:58:52:574 CDT] 00000061 SystemOut O 89811715 [WebContainer : 2] INFO org.apache.jackrabbit.core.observation.ObservationDispatcher - Notification of EventListeners stopped.
[4/23/08 9:58:53:058 CDT] 00000061 SystemOut O 89812215 [WebContainer : 2] INFO org.apache.jackrabbit.core.RepositoryImpl - workspace 'default' has been shutdown
[4/23/08 9:58:53:308 CDT] 00000308 SystemOut O 91641048 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7165
[4/23/08 9:58:53:324 CDT] 00000308 SystemOut O 91641064 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7166
[4/23/08 9:58:53:324 CDT] 00000308 SystemOut O 91641064 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7167
[4/23/08 9:58:53:339 CDT] 00000308 SystemOut O 91641079 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7168
[4/23/08 9:58:53:339 CDT] 00000308 SystemOut O 91641079 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7169
[4/23/08 9:58:53:355 CDT] 00000308 SystemOut O 91641095 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7170
[4/23/08 9:58:53:371 CDT] 00000308 SystemOut O 91641111 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7171
[4/23/08 9:58:53:386 CDT] 00000308 SystemOut O 91641126 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7172
[4/23/08 9:58:53:417 CDT] 00000308 SystemOut O 91641157 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7173
[4/23/08 9:58:53:433 CDT] 00000308 SystemOut O 91641173 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7174
[4/23/08 9:58:53:433 CDT] 00000308 SystemOut O 91641173 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7175
[4/23/08 9:58:53:496 CDT] 00000308 SystemOut O 91641236 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.journal.AbstractJournal - Synchronized to revision: 7175
[4/23/08 9:58:54:292 CDT] 00000131 SystemOut O 89171473 [ClusterNode-4930503b-ab33-4444-999e-c87fb3681bf7] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7173
[4/23/08 9:58:54:308 CDT] 00000131 SystemOut O 89171504 [ClusterNode-4930503b-ab33-4444-999e-c87fb3681bf7] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7174
[4/23/08 9:58:54:308 CDT] 00000131 SystemOut O 89171504 [ClusterNode-4930503b-ab33-4444-999e-c87fb3681bf7] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7175
[4/23/08 9:58:54:386 CDT] 00000131 SystemOut O 89171582 [ClusterNode-4930503b-ab33-4444-999e-c87fb3681bf7] INFO org.apache.jackrabbit.core.journal.AbstractJournal - Synchronized to revision: 7175
[4/23/08 9:58:55:417 CDT] 00000061 SystemOut O 89814574 [WebContainer : 2] INFO org.apache.jackrabbit.core.RepositoryImpl - Repository has been shutdown
[4/23/08 9:58:56:089 CDT] 0000054c SystemOut O 89815199 [ClusterNode-b06e4fe7-a602-4a93-b106-e0834046ae0f] ERROR org.apache.jackrabbit.core.cluster.ClusterNode - Unable to read revision '7174'.
org.apache.jackrabbit.core.journal.JournalException: I/O error while reading string.
at org.apache.jackrabbit.core.journal.ReadRecord.readString(ReadRecord.java:169)
at org.apache.jackrabbit.core.cluster.ClusterNode.consume(ClusterNode.java:979)
at org.apache.jackrabbit.core.journal.AbstractJournal.doSync(AbstractJournal.java:198)
at org.apache.jackrabbit.core.journal.AbstractJournal.sync(AbstractJournal.java:173)
at org.apache.jackrabbit.core.cluster.ClusterNode.sync(ClusterNode.java:303)
at org.apache.jackrabbit.core.cluster.ClusterNode.run(ClusterNode.java:274)
at java.lang.Thread.run(Thread.java:797)
Caused by:
java.io.IOException: Closed Connection
at oracle.jdbc.driver.DatabaseError.SQLToIOException(DatabaseError.java:517)
at oracle.jdbc.driver.OracleBlobInputStream.needBytes(OracleBlobInputStream.java:187)
at oracle.jdbc.driver.OracleBufferedStream.readInternal(OracleBufferedStream.java:130)
at oracle.jdbc.driver.OracleBufferedStream.read(OracleBufferedStream.java:108)
at java.io.DataInputStream.readBoolean(DataInputStream.java:246)
at org.apache.jackrabbit.core.journal.ReadRecord.readString(ReadRecord.java:161)
... 6 more
[4/23/08 9:58:56:261 CDT] 0000054c SystemOut O 89815355 [ClusterNode-b06e4fe7-a602-4a93-b106-e0834046ae0f] ERROR org.apache.jackrabbit.core.journal.DatabaseJournal - Error while moving to next record.
java.sql.SQLException: Closed Connection: next
at oracle.jdbc.driver.DatabaseError.throwSqlException(DatabaseError.java:112)
at oracle.jdbc.driver.DatabaseError.throwSqlException(DatabaseError.java:146)
at oracle.jdbc.driver.OracleResultSetImpl.next(OracleResultSetImpl.java:181)
at org.apache.jackrabbit.core.journal.DatabaseRecordIterator.fetchRecord(DatabaseRecordIterator.java:136)
at org.apache.jackrabbit.core.journal.DatabaseRecordIterator.hasNext(DatabaseRecordIterator.java:85)
at org.apache.jackrabbit.core.journal.AbstractJournal.doSync(AbstractJournal.java:190)
at org.apache.jackrabbit.core.journal.AbstractJournal.sync(AbstractJournal.java:173)
at org.apache.jackrabbit.core.cluster.ClusterNode.sync(ClusterNode.java:303)
at org.apache.jackrabbit.core.cluster.ClusterNode.run(ClusterNode.java:274)
at java.lang.Thread.run(Thread.java:797)
[4/23/08 9:58:56:402 CDT] 0000054c SystemOut O 89815418 [ClusterNode-b06e4fe7-a602-4a93-b106-e0834046ae0f] WARN org.apache.jackrabbit.core.cluster.ClusterNode - Unable to set current revision to 7174.
org.apache.jackrabbit.core.journal.JournalException: Revision file closed.
at org.apache.jackrabbit.core.journal.FileRevision.set(FileRevision.java:100)
at org.apache.jackrabbit.core.cluster.ClusterNode.setRevision(ClusterNode.java:1073)
at org.apache.jackrabbit.core.journal.AbstractJournal.doSync(AbstractJournal.java:211)
at org.apache.jackrabbit.core.journal.AbstractJournal.sync(AbstractJournal.java:173)
at org.apache.jackrabbit.core.cluster.ClusterNode.sync(ClusterNode.java:303)
at org.apache.jackrabbit.core.cluster.ClusterNode.run(ClusterNode.java:274)
at java.lang.Thread.run(Thread.java:797)
--
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.
[jira] Assigned: (JCR-1553) ClusterNode not properly shutdown when
repository has shutdown
Posted by "Dominique Pfister (JIRA)" <ji...@apache.org>.
[ https://issues.apache.org/jira/browse/JCR-1553?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Dominique Pfister reassigned JCR-1553:
--------------------------------------
Assignee: Dominique Pfister
> ClusterNode not properly shutdown when repository has shutdown
> --------------------------------------------------------------
>
> Key: JCR-1553
> URL: https://issues.apache.org/jira/browse/JCR-1553
> Project: Jackrabbit
> Issue Type: Bug
> Components: clustering, jackrabbit-core
> Affects Versions: core 1.4.1
> Environment: windows vista jdk 1.5.0_11.
> Reporter: Micah Whitacre
> Assignee: Dominique Pfister
>
> Sometimes when the repository is shutdown the ClusterNode is not shutdown and it therefore tries to update records or access a closed Journal file. The setup that generated the exception is I have 3 VMs each with a Repository that are all connected to the same database. In the below stack trace one of the repositories is being shutdown however the ClusterNode thread is also trying to update the repository journal at the same time. Below is a copy of the stack trace.
> [4/23/08 9:58:52:496 CDT] 00000061 SystemOut O 89811653 [WebContainer : 2] INFO org.apache.jackrabbit.core.RepositoryImpl - Shutting down repository...
> [4/23/08 9:58:52:511 CDT] 0000054c SystemOut O 89811621 [ClusterNode-b06e4fe7-a602-4a93-b106-e0834046ae0f] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7174
> [4/23/08 9:58:52:527 CDT] 00000061 SystemOut O 89811684 [WebContainer : 2] INFO org.apache.jackrabbit.core.RepositoryImpl - shutting down workspace 'default'...
> [4/23/08 9:58:52:574 CDT] 00000061 SystemOut O 89811715 [WebContainer : 2] INFO org.apache.jackrabbit.core.observation.ObservationDispatcher - Notification of EventListeners stopped.
> [4/23/08 9:58:53:058 CDT] 00000061 SystemOut O 89812215 [WebContainer : 2] INFO org.apache.jackrabbit.core.RepositoryImpl - workspace 'default' has been shutdown
> [4/23/08 9:58:53:308 CDT] 00000308 SystemOut O 91641048 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7165
> [4/23/08 9:58:53:324 CDT] 00000308 SystemOut O 91641064 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7166
> [4/23/08 9:58:53:324 CDT] 00000308 SystemOut O 91641064 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7167
> [4/23/08 9:58:53:339 CDT] 00000308 SystemOut O 91641079 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7168
> [4/23/08 9:58:53:339 CDT] 00000308 SystemOut O 91641079 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7169
> [4/23/08 9:58:53:355 CDT] 00000308 SystemOut O 91641095 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7170
> [4/23/08 9:58:53:371 CDT] 00000308 SystemOut O 91641111 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7171
> [4/23/08 9:58:53:386 CDT] 00000308 SystemOut O 91641126 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7172
> [4/23/08 9:58:53:417 CDT] 00000308 SystemOut O 91641157 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7173
> [4/23/08 9:58:53:433 CDT] 00000308 SystemOut O 91641173 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7174
> [4/23/08 9:58:53:433 CDT] 00000308 SystemOut O 91641173 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7175
> [4/23/08 9:58:53:496 CDT] 00000308 SystemOut O 91641236 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.journal.AbstractJournal - Synchronized to revision: 7175
> [4/23/08 9:58:54:292 CDT] 00000131 SystemOut O 89171473 [ClusterNode-4930503b-ab33-4444-999e-c87fb3681bf7] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7173
> [4/23/08 9:58:54:308 CDT] 00000131 SystemOut O 89171504 [ClusterNode-4930503b-ab33-4444-999e-c87fb3681bf7] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7174
> [4/23/08 9:58:54:308 CDT] 00000131 SystemOut O 89171504 [ClusterNode-4930503b-ab33-4444-999e-c87fb3681bf7] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7175
> [4/23/08 9:58:54:386 CDT] 00000131 SystemOut O 89171582 [ClusterNode-4930503b-ab33-4444-999e-c87fb3681bf7] INFO org.apache.jackrabbit.core.journal.AbstractJournal - Synchronized to revision: 7175
> [4/23/08 9:58:55:417 CDT] 00000061 SystemOut O 89814574 [WebContainer : 2] INFO org.apache.jackrabbit.core.RepositoryImpl - Repository has been shutdown
> [4/23/08 9:58:56:089 CDT] 0000054c SystemOut O 89815199 [ClusterNode-b06e4fe7-a602-4a93-b106-e0834046ae0f] ERROR org.apache.jackrabbit.core.cluster.ClusterNode - Unable to read revision '7174'.
> org.apache.jackrabbit.core.journal.JournalException: I/O error while reading string.
> at org.apache.jackrabbit.core.journal.ReadRecord.readString(ReadRecord.java:169)
> at org.apache.jackrabbit.core.cluster.ClusterNode.consume(ClusterNode.java:979)
> at org.apache.jackrabbit.core.journal.AbstractJournal.doSync(AbstractJournal.java:198)
> at org.apache.jackrabbit.core.journal.AbstractJournal.sync(AbstractJournal.java:173)
> at org.apache.jackrabbit.core.cluster.ClusterNode.sync(ClusterNode.java:303)
> at org.apache.jackrabbit.core.cluster.ClusterNode.run(ClusterNode.java:274)
> at java.lang.Thread.run(Thread.java:797)
> Caused by:
> java.io.IOException: Closed Connection
> at oracle.jdbc.driver.DatabaseError.SQLToIOException(DatabaseError.java:517)
> at oracle.jdbc.driver.OracleBlobInputStream.needBytes(OracleBlobInputStream.java:187)
> at oracle.jdbc.driver.OracleBufferedStream.readInternal(OracleBufferedStream.java:130)
> at oracle.jdbc.driver.OracleBufferedStream.read(OracleBufferedStream.java:108)
> at java.io.DataInputStream.readBoolean(DataInputStream.java:246)
> at org.apache.jackrabbit.core.journal.ReadRecord.readString(ReadRecord.java:161)
> ... 6 more
> [4/23/08 9:58:56:261 CDT] 0000054c SystemOut O 89815355 [ClusterNode-b06e4fe7-a602-4a93-b106-e0834046ae0f] ERROR org.apache.jackrabbit.core.journal.DatabaseJournal - Error while moving to next record.
> java.sql.SQLException: Closed Connection: next
> at oracle.jdbc.driver.DatabaseError.throwSqlException(DatabaseError.java:112)
> at oracle.jdbc.driver.DatabaseError.throwSqlException(DatabaseError.java:146)
> at oracle.jdbc.driver.OracleResultSetImpl.next(OracleResultSetImpl.java:181)
> at org.apache.jackrabbit.core.journal.DatabaseRecordIterator.fetchRecord(DatabaseRecordIterator.java:136)
> at org.apache.jackrabbit.core.journal.DatabaseRecordIterator.hasNext(DatabaseRecordIterator.java:85)
> at org.apache.jackrabbit.core.journal.AbstractJournal.doSync(AbstractJournal.java:190)
> at org.apache.jackrabbit.core.journal.AbstractJournal.sync(AbstractJournal.java:173)
> at org.apache.jackrabbit.core.cluster.ClusterNode.sync(ClusterNode.java:303)
> at org.apache.jackrabbit.core.cluster.ClusterNode.run(ClusterNode.java:274)
> at java.lang.Thread.run(Thread.java:797)
> [4/23/08 9:58:56:402 CDT] 0000054c SystemOut O 89815418 [ClusterNode-b06e4fe7-a602-4a93-b106-e0834046ae0f] WARN org.apache.jackrabbit.core.cluster.ClusterNode - Unable to set current revision to 7174.
> org.apache.jackrabbit.core.journal.JournalException: Revision file closed.
> at org.apache.jackrabbit.core.journal.FileRevision.set(FileRevision.java:100)
> at org.apache.jackrabbit.core.cluster.ClusterNode.setRevision(ClusterNode.java:1073)
> at org.apache.jackrabbit.core.journal.AbstractJournal.doSync(AbstractJournal.java:211)
> at org.apache.jackrabbit.core.journal.AbstractJournal.sync(AbstractJournal.java:173)
> at org.apache.jackrabbit.core.cluster.ClusterNode.sync(ClusterNode.java:303)
> at org.apache.jackrabbit.core.cluster.ClusterNode.run(ClusterNode.java:274)
> at java.lang.Thread.run(Thread.java:797)
--
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.
[jira] Closed: (JCR-1553) ClusterNode not properly shutdown when
repository has shutdown
Posted by "Dominique Pfister (JIRA)" <ji...@apache.org>.
[ https://issues.apache.org/jira/browse/JCR-1553?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Dominique Pfister closed JCR-1553.
----------------------------------
Resolution: Fixed
Fix Version/s: 1.5.0
ClusterNode.stop() will now wait for at most 5 seconds (configurable through setStopDelay()) for the synchronization thread to stop processing incoming records, which should work for most cases. If a cluster node is processing a lot of records, it may, however, still be interrupted in the middle of processing them; a better fix would have the synchronization thread look at the state of the cluster node periodically.
Fixed in revision 708199.
> ClusterNode not properly shutdown when repository has shutdown
> --------------------------------------------------------------
>
> Key: JCR-1553
> URL: https://issues.apache.org/jira/browse/JCR-1553
> Project: Jackrabbit
> Issue Type: Bug
> Components: clustering, jackrabbit-core
> Affects Versions: core 1.4.1
> Environment: windows vista jdk 1.5.0_11.
> Reporter: Micah Whitacre
> Assignee: Dominique Pfister
> Fix For: 1.5.0
>
>
> Sometimes when the repository is shutdown the ClusterNode is not shutdown and it therefore tries to update records or access a closed Journal file. The setup that generated the exception is I have 3 VMs each with a Repository that are all connected to the same database. In the below stack trace one of the repositories is being shutdown however the ClusterNode thread is also trying to update the repository journal at the same time. Below is a copy of the stack trace.
> [4/23/08 9:58:52:496 CDT] 00000061 SystemOut O 89811653 [WebContainer : 2] INFO org.apache.jackrabbit.core.RepositoryImpl - Shutting down repository...
> [4/23/08 9:58:52:511 CDT] 0000054c SystemOut O 89811621 [ClusterNode-b06e4fe7-a602-4a93-b106-e0834046ae0f] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7174
> [4/23/08 9:58:52:527 CDT] 00000061 SystemOut O 89811684 [WebContainer : 2] INFO org.apache.jackrabbit.core.RepositoryImpl - shutting down workspace 'default'...
> [4/23/08 9:58:52:574 CDT] 00000061 SystemOut O 89811715 [WebContainer : 2] INFO org.apache.jackrabbit.core.observation.ObservationDispatcher - Notification of EventListeners stopped.
> [4/23/08 9:58:53:058 CDT] 00000061 SystemOut O 89812215 [WebContainer : 2] INFO org.apache.jackrabbit.core.RepositoryImpl - workspace 'default' has been shutdown
> [4/23/08 9:58:53:308 CDT] 00000308 SystemOut O 91641048 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7165
> [4/23/08 9:58:53:324 CDT] 00000308 SystemOut O 91641064 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7166
> [4/23/08 9:58:53:324 CDT] 00000308 SystemOut O 91641064 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7167
> [4/23/08 9:58:53:339 CDT] 00000308 SystemOut O 91641079 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7168
> [4/23/08 9:58:53:339 CDT] 00000308 SystemOut O 91641079 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7169
> [4/23/08 9:58:53:355 CDT] 00000308 SystemOut O 91641095 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7170
> [4/23/08 9:58:53:371 CDT] 00000308 SystemOut O 91641111 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7171
> [4/23/08 9:58:53:386 CDT] 00000308 SystemOut O 91641126 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7172
> [4/23/08 9:58:53:417 CDT] 00000308 SystemOut O 91641157 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7173
> [4/23/08 9:58:53:433 CDT] 00000308 SystemOut O 91641173 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7174
> [4/23/08 9:58:53:433 CDT] 00000308 SystemOut O 91641173 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7175
> [4/23/08 9:58:53:496 CDT] 00000308 SystemOut O 91641236 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.journal.AbstractJournal - Synchronized to revision: 7175
> [4/23/08 9:58:54:292 CDT] 00000131 SystemOut O 89171473 [ClusterNode-4930503b-ab33-4444-999e-c87fb3681bf7] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7173
> [4/23/08 9:58:54:308 CDT] 00000131 SystemOut O 89171504 [ClusterNode-4930503b-ab33-4444-999e-c87fb3681bf7] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7174
> [4/23/08 9:58:54:308 CDT] 00000131 SystemOut O 89171504 [ClusterNode-4930503b-ab33-4444-999e-c87fb3681bf7] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7175
> [4/23/08 9:58:54:386 CDT] 00000131 SystemOut O 89171582 [ClusterNode-4930503b-ab33-4444-999e-c87fb3681bf7] INFO org.apache.jackrabbit.core.journal.AbstractJournal - Synchronized to revision: 7175
> [4/23/08 9:58:55:417 CDT] 00000061 SystemOut O 89814574 [WebContainer : 2] INFO org.apache.jackrabbit.core.RepositoryImpl - Repository has been shutdown
> [4/23/08 9:58:56:089 CDT] 0000054c SystemOut O 89815199 [ClusterNode-b06e4fe7-a602-4a93-b106-e0834046ae0f] ERROR org.apache.jackrabbit.core.cluster.ClusterNode - Unable to read revision '7174'.
> org.apache.jackrabbit.core.journal.JournalException: I/O error while reading string.
> at org.apache.jackrabbit.core.journal.ReadRecord.readString(ReadRecord.java:169)
> at org.apache.jackrabbit.core.cluster.ClusterNode.consume(ClusterNode.java:979)
> at org.apache.jackrabbit.core.journal.AbstractJournal.doSync(AbstractJournal.java:198)
> at org.apache.jackrabbit.core.journal.AbstractJournal.sync(AbstractJournal.java:173)
> at org.apache.jackrabbit.core.cluster.ClusterNode.sync(ClusterNode.java:303)
> at org.apache.jackrabbit.core.cluster.ClusterNode.run(ClusterNode.java:274)
> at java.lang.Thread.run(Thread.java:797)
> Caused by:
> java.io.IOException: Closed Connection
> at oracle.jdbc.driver.DatabaseError.SQLToIOException(DatabaseError.java:517)
> at oracle.jdbc.driver.OracleBlobInputStream.needBytes(OracleBlobInputStream.java:187)
> at oracle.jdbc.driver.OracleBufferedStream.readInternal(OracleBufferedStream.java:130)
> at oracle.jdbc.driver.OracleBufferedStream.read(OracleBufferedStream.java:108)
> at java.io.DataInputStream.readBoolean(DataInputStream.java:246)
> at org.apache.jackrabbit.core.journal.ReadRecord.readString(ReadRecord.java:161)
> ... 6 more
> [4/23/08 9:58:56:261 CDT] 0000054c SystemOut O 89815355 [ClusterNode-b06e4fe7-a602-4a93-b106-e0834046ae0f] ERROR org.apache.jackrabbit.core.journal.DatabaseJournal - Error while moving to next record.
> java.sql.SQLException: Closed Connection: next
> at oracle.jdbc.driver.DatabaseError.throwSqlException(DatabaseError.java:112)
> at oracle.jdbc.driver.DatabaseError.throwSqlException(DatabaseError.java:146)
> at oracle.jdbc.driver.OracleResultSetImpl.next(OracleResultSetImpl.java:181)
> at org.apache.jackrabbit.core.journal.DatabaseRecordIterator.fetchRecord(DatabaseRecordIterator.java:136)
> at org.apache.jackrabbit.core.journal.DatabaseRecordIterator.hasNext(DatabaseRecordIterator.java:85)
> at org.apache.jackrabbit.core.journal.AbstractJournal.doSync(AbstractJournal.java:190)
> at org.apache.jackrabbit.core.journal.AbstractJournal.sync(AbstractJournal.java:173)
> at org.apache.jackrabbit.core.cluster.ClusterNode.sync(ClusterNode.java:303)
> at org.apache.jackrabbit.core.cluster.ClusterNode.run(ClusterNode.java:274)
> at java.lang.Thread.run(Thread.java:797)
> [4/23/08 9:58:56:402 CDT] 0000054c SystemOut O 89815418 [ClusterNode-b06e4fe7-a602-4a93-b106-e0834046ae0f] WARN org.apache.jackrabbit.core.cluster.ClusterNode - Unable to set current revision to 7174.
> org.apache.jackrabbit.core.journal.JournalException: Revision file closed.
> at org.apache.jackrabbit.core.journal.FileRevision.set(FileRevision.java:100)
> at org.apache.jackrabbit.core.cluster.ClusterNode.setRevision(ClusterNode.java:1073)
> at org.apache.jackrabbit.core.journal.AbstractJournal.doSync(AbstractJournal.java:211)
> at org.apache.jackrabbit.core.journal.AbstractJournal.sync(AbstractJournal.java:173)
> at org.apache.jackrabbit.core.cluster.ClusterNode.sync(ClusterNode.java:303)
> at org.apache.jackrabbit.core.cluster.ClusterNode.run(ClusterNode.java:274)
> at java.lang.Thread.run(Thread.java:797)
--
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.
[jira] Commented: (JCR-1553) ClusterNode not properly shutdown when
repository has shutdown
Posted by "Dominique Pfister (JIRA)" <ji...@apache.org>.
[ https://issues.apache.org/jira/browse/JCR-1553?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=12642935#action_12642935 ]
Dominique Pfister commented on JCR-1553:
----------------------------------------
Fixed in trunk in revision 708206.
> ClusterNode not properly shutdown when repository has shutdown
> --------------------------------------------------------------
>
> Key: JCR-1553
> URL: https://issues.apache.org/jira/browse/JCR-1553
> Project: Jackrabbit
> Issue Type: Bug
> Components: clustering, jackrabbit-core
> Affects Versions: core 1.4.1
> Environment: windows vista jdk 1.5.0_11.
> Reporter: Micah Whitacre
> Assignee: Dominique Pfister
> Fix For: 1.5.0
>
>
> Sometimes when the repository is shutdown the ClusterNode is not shutdown and it therefore tries to update records or access a closed Journal file. The setup that generated the exception is I have 3 VMs each with a Repository that are all connected to the same database. In the below stack trace one of the repositories is being shutdown however the ClusterNode thread is also trying to update the repository journal at the same time. Below is a copy of the stack trace.
> [4/23/08 9:58:52:496 CDT] 00000061 SystemOut O 89811653 [WebContainer : 2] INFO org.apache.jackrabbit.core.RepositoryImpl - Shutting down repository...
> [4/23/08 9:58:52:511 CDT] 0000054c SystemOut O 89811621 [ClusterNode-b06e4fe7-a602-4a93-b106-e0834046ae0f] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7174
> [4/23/08 9:58:52:527 CDT] 00000061 SystemOut O 89811684 [WebContainer : 2] INFO org.apache.jackrabbit.core.RepositoryImpl - shutting down workspace 'default'...
> [4/23/08 9:58:52:574 CDT] 00000061 SystemOut O 89811715 [WebContainer : 2] INFO org.apache.jackrabbit.core.observation.ObservationDispatcher - Notification of EventListeners stopped.
> [4/23/08 9:58:53:058 CDT] 00000061 SystemOut O 89812215 [WebContainer : 2] INFO org.apache.jackrabbit.core.RepositoryImpl - workspace 'default' has been shutdown
> [4/23/08 9:58:53:308 CDT] 00000308 SystemOut O 91641048 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7165
> [4/23/08 9:58:53:324 CDT] 00000308 SystemOut O 91641064 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7166
> [4/23/08 9:58:53:324 CDT] 00000308 SystemOut O 91641064 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7167
> [4/23/08 9:58:53:339 CDT] 00000308 SystemOut O 91641079 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7168
> [4/23/08 9:58:53:339 CDT] 00000308 SystemOut O 91641079 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7169
> [4/23/08 9:58:53:355 CDT] 00000308 SystemOut O 91641095 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7170
> [4/23/08 9:58:53:371 CDT] 00000308 SystemOut O 91641111 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7171
> [4/23/08 9:58:53:386 CDT] 00000308 SystemOut O 91641126 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7172
> [4/23/08 9:58:53:417 CDT] 00000308 SystemOut O 91641157 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7173
> [4/23/08 9:58:53:433 CDT] 00000308 SystemOut O 91641173 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7174
> [4/23/08 9:58:53:433 CDT] 00000308 SystemOut O 91641173 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7175
> [4/23/08 9:58:53:496 CDT] 00000308 SystemOut O 91641236 [ClusterNode-e609e8a6-320e-44ea-be0f-ab8c5cb89662] INFO org.apache.jackrabbit.core.journal.AbstractJournal - Synchronized to revision: 7175
> [4/23/08 9:58:54:292 CDT] 00000131 SystemOut O 89171473 [ClusterNode-4930503b-ab33-4444-999e-c87fb3681bf7] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7173
> [4/23/08 9:58:54:308 CDT] 00000131 SystemOut O 89171504 [ClusterNode-4930503b-ab33-4444-999e-c87fb3681bf7] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7174
> [4/23/08 9:58:54:308 CDT] 00000131 SystemOut O 89171504 [ClusterNode-4930503b-ab33-4444-999e-c87fb3681bf7] INFO org.apache.jackrabbit.core.cluster.ClusterNode - Processing revision: 7175
> [4/23/08 9:58:54:386 CDT] 00000131 SystemOut O 89171582 [ClusterNode-4930503b-ab33-4444-999e-c87fb3681bf7] INFO org.apache.jackrabbit.core.journal.AbstractJournal - Synchronized to revision: 7175
> [4/23/08 9:58:55:417 CDT] 00000061 SystemOut O 89814574 [WebContainer : 2] INFO org.apache.jackrabbit.core.RepositoryImpl - Repository has been shutdown
> [4/23/08 9:58:56:089 CDT] 0000054c SystemOut O 89815199 [ClusterNode-b06e4fe7-a602-4a93-b106-e0834046ae0f] ERROR org.apache.jackrabbit.core.cluster.ClusterNode - Unable to read revision '7174'.
> org.apache.jackrabbit.core.journal.JournalException: I/O error while reading string.
> at org.apache.jackrabbit.core.journal.ReadRecord.readString(ReadRecord.java:169)
> at org.apache.jackrabbit.core.cluster.ClusterNode.consume(ClusterNode.java:979)
> at org.apache.jackrabbit.core.journal.AbstractJournal.doSync(AbstractJournal.java:198)
> at org.apache.jackrabbit.core.journal.AbstractJournal.sync(AbstractJournal.java:173)
> at org.apache.jackrabbit.core.cluster.ClusterNode.sync(ClusterNode.java:303)
> at org.apache.jackrabbit.core.cluster.ClusterNode.run(ClusterNode.java:274)
> at java.lang.Thread.run(Thread.java:797)
> Caused by:
> java.io.IOException: Closed Connection
> at oracle.jdbc.driver.DatabaseError.SQLToIOException(DatabaseError.java:517)
> at oracle.jdbc.driver.OracleBlobInputStream.needBytes(OracleBlobInputStream.java:187)
> at oracle.jdbc.driver.OracleBufferedStream.readInternal(OracleBufferedStream.java:130)
> at oracle.jdbc.driver.OracleBufferedStream.read(OracleBufferedStream.java:108)
> at java.io.DataInputStream.readBoolean(DataInputStream.java:246)
> at org.apache.jackrabbit.core.journal.ReadRecord.readString(ReadRecord.java:161)
> ... 6 more
> [4/23/08 9:58:56:261 CDT] 0000054c SystemOut O 89815355 [ClusterNode-b06e4fe7-a602-4a93-b106-e0834046ae0f] ERROR org.apache.jackrabbit.core.journal.DatabaseJournal - Error while moving to next record.
> java.sql.SQLException: Closed Connection: next
> at oracle.jdbc.driver.DatabaseError.throwSqlException(DatabaseError.java:112)
> at oracle.jdbc.driver.DatabaseError.throwSqlException(DatabaseError.java:146)
> at oracle.jdbc.driver.OracleResultSetImpl.next(OracleResultSetImpl.java:181)
> at org.apache.jackrabbit.core.journal.DatabaseRecordIterator.fetchRecord(DatabaseRecordIterator.java:136)
> at org.apache.jackrabbit.core.journal.DatabaseRecordIterator.hasNext(DatabaseRecordIterator.java:85)
> at org.apache.jackrabbit.core.journal.AbstractJournal.doSync(AbstractJournal.java:190)
> at org.apache.jackrabbit.core.journal.AbstractJournal.sync(AbstractJournal.java:173)
> at org.apache.jackrabbit.core.cluster.ClusterNode.sync(ClusterNode.java:303)
> at org.apache.jackrabbit.core.cluster.ClusterNode.run(ClusterNode.java:274)
> at java.lang.Thread.run(Thread.java:797)
> [4/23/08 9:58:56:402 CDT] 0000054c SystemOut O 89815418 [ClusterNode-b06e4fe7-a602-4a93-b106-e0834046ae0f] WARN org.apache.jackrabbit.core.cluster.ClusterNode - Unable to set current revision to 7174.
> org.apache.jackrabbit.core.journal.JournalException: Revision file closed.
> at org.apache.jackrabbit.core.journal.FileRevision.set(FileRevision.java:100)
> at org.apache.jackrabbit.core.cluster.ClusterNode.setRevision(ClusterNode.java:1073)
> at org.apache.jackrabbit.core.journal.AbstractJournal.doSync(AbstractJournal.java:211)
> at org.apache.jackrabbit.core.journal.AbstractJournal.sync(AbstractJournal.java:173)
> at org.apache.jackrabbit.core.cluster.ClusterNode.sync(ClusterNode.java:303)
> at org.apache.jackrabbit.core.cluster.ClusterNode.run(ClusterNode.java:274)
> at java.lang.Thread.run(Thread.java:797)
--
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.