testStalePlanCheckIntervalOutOfRange(org.apache.derbyTesting.functionTests.tests.lang.StalePlansTest) fails with java.sql.SQLException: Log Record has been sent to the stream, but it cannot be applied to the store (Object null)

• Key: DERBY-3052
• Type: Bug
• Status: Closed
• Resolution: Duplicate
• Priority: Critical
• Assignee: Unassigned
• Reporter: Kathey Marsden
• Component/s: Test
• Affects Version/s: 10.3.2.1, 10.4.1.3

Environment

OS:Windows 0.4.0.0 alpha - (572851)
IBM 1.4.2

Description

On 9/4 I saw this failure with IBM 1.4.2 on Windows. cannot reproduce.


1) testStalePlanCheckIntervalOutOfRange(org.apache.derbyTesting.functionTests.tests.lang.StalePlansTest)java.sql.SQLException: Log Record has been sent to the stream, but it cannot be applied to the store (Object null). This may cause recovery problems also.
at org.apache.derby.impl.jdbc.SQLExceptionFactory.getSQLException(Unknown Source)
at org.apache.derby.impl.jdbc.Util.newEmbedSQLException(Unknown Source)
at org.apache.derby.impl.jdbc.Util.seeNextException(Unknown Source)
at org.apache.derby.impl.jdbc.TransactionResourceImpl.wrapInSQLException(Unknown Source)
at org.apache.derby.impl.jdbc.TransactionResourceImpl.handleException(Unknown Source)
at org.apache.derby.impl.jdbc.EmbedConnection.handleException(Unknown Source)
at org.apache.derby.impl.jdbc.ConnectionChild.handleException(Unknown Source)
at org.apache.derby.impl.jdbc.EmbedStatement.executeStatement(Unknown Source)
at org.apache.derby.impl.jdbc.EmbedStatement.execute(Unknown Source)
at org.apache.derby.impl.jdbc.EmbedStatement.executeUpdate(Unknown Source)
at org.apache.derbyTesting.functionTests.tests.lang.StalePlansTest.setUp(StalePlansTest.java:83)
at org.apache.derbyTesting.junit.BaseTestCase.runBare(BaseTestCase.java:95)
at junit.extensions.TestDecorator.basicRun(TestDecorator.java:22)
at junit.extensions.TestSetup$1.protect(TestSetup.java:19)
at junit.extensions.TestSetup.run(TestSetup.java:23)
at org.apache.derbyTesting.junit.BaseTestSetup.run(BaseTestSetup.java:57)
at junit.extensions.TestDecorator.basicRun(TestDecorator.java:22)
at junit.extensions.TestSetup$1.protect(TestSetup.java:19)
at junit.extensions.TestSetup.run(TestSetup.java:23)
at org.apache.derbyTesting.junit.BaseTestSetup.run(BaseTestSetup.java:57)
Caused by: java.sql.SQLException: The store has been marked for shutdown by an earlier exception.
at org.apache.derby.impl.jdbc.SQLExceptionFactory.getSQLException(Unknown Source)
at org.apache.derby.impl.jdbc.Util.newEmbedSQLException(Unknown Source)
at org.apache.derby.impl.jdbc.Util.seeNextException(Unknown Source)
at org.apache.derby.impl.jdbc.TransactionResourceImpl.wrapInSQLException(Unknown Source)
... 35 more
Caused by: java.sql.SQLException: Meta-data for Container org.apache.derby.impl.store.raw.data.RAFContainer4@54ec81f1 could not be accessed
at org.apache.derby.impl.jdbc.SQLExceptionFactory.getSQLException(Unknown Source)
at org.apache.derby.impl.jdbc.Util.newEmbedSQLException(Unknown Source)
at org.apache.derby.impl.jdbc.Util.seeNextException(Unknown Source)
at org.apache.derby.impl.jdbc.TransactionResourceImpl.wrapInSQLException(Unknown Source)
... 36 more
Caused by: java.sql.SQLException: Java exception: ': java.io.EOFException'.
at org.apache.derby.impl.jdbc.SQLExceptionFactory.getSQLException(Unknown Source)
at org.apache.derby.impl.jdbc.Util.newEmbedSQLException(Unknown Source)
at org.apache.derby.impl.jdbc.Util.javaException(Unknown Source)
at org.apache.derby.impl.jdbc.TransactionResourceImpl.wrapInSQLException(Unknown Source)
... 37 more
Caused by: java.io.EOFException
at java.io.RandomAccessFile.readFully(RandomAccessFile.java:394)
at java.io.RandomAccessFile.readFully(RandomAccessFile.java:372)
at org.apache.derby.impl.store.raw.data.FileContainer.getEmbryonicPage(Unknown Source)
at org.apache.derby.impl.store.raw.data.RAFContainer.writeRAFHeader(Unknown Source)
at org.apache.derby.impl.store.raw.data.RAFContainer.clean(Unknown Source)
at org.apache.derby.impl.services.cache.CachedItem.clean(Unknown Source)
at org.apache.derby.impl.services.cache.Clock.cleanCache(Unknown Source)
at org.apache.derby.impl.services.cache.Clock.cleanAll(Unknown Source)
at org.apache.derby.impl.store.raw.data.BaseDataFileFactory.checkpoint(Unknown Source)
at org.apache.derby.impl.store.raw.log.LogToFile.checkpointWithTran(Unknown Source)
at org.apache.derby.impl.store.raw.log.LogToFile.checkpoint(Unknown Source)
at org.apache.derby.impl.store.raw.RawStore.checkpoint(Unknown Source)
at org.apache.derby.impl.store.raw.log.LogToFile.performWork(Unknown Source)
at org.apache.derby.impl.services.daemon.BasicDaemon.serviceClient(Unknown Source)
at org.apache.derby.impl.services.daemon.BasicDaemon.work(Unknown Source)
at org.apache.derby.impl.services.daemon.BasicDaemon.run(Unknown Source)
at java.lang.Thread.run(Thread.java:570)
There was 1 failure:

Comments

[Knut Anders Hatlen]

The stack trace looks suspiciously similar to DERBY-2144 and DERBY-2589.

[Manjula Kutty]

Happened again 11/02 with similar stack trace.

[Kathey Marsden]

I saw this with IBM 1.4.2 on 11/25/07 with the 10.3 branch with Windows

[Kathey Marsden]

Changing affects version since this has occurred on the 10.3 branch.

[Mamta A. Satoor]

This happened on trunk 10.4.0.0 alpha - (609841) with ibm142

[Kathey Marsden]

Saw this 2/15/2008 on IBM 1.4.2

[Manjula Kutty]

Happend again on 03/27/08 on ibm142

[Mamta A. Satoor]

Noticed this again on April 3rd with ibm142

[Rick Hillegas]

Has this issue been seen on Java 5? Knut (05/Sep/07) noted that this report is suspiciously similar to DERBY-2144, which Kathey resolved as a JVM bug. According to the link Kathey posted (http://bugs.sun.com/bugdatabase/view_bug.do?bug_id=4189011), that JVM bug appears to be fixed in Java 5. All of the instances of this problem (in this JIRA) are reported against jdk1.4.2.

[Knut Anders Hatlen]

Actually, I think this is the same problem as DERBY-3347. I'm marking the issue as resolved. If it happens again, we can reopen the issue or create a new one.