[
https://issues.apache.org/jira/browse/DERBY-5428?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13113548#comment-13113548
]
Myrna van Lunteren commented on DERBY-5428:
-------------------------------------------
Hi Rick,
The only instructions I know of are in the README.txt file you can find in the
source under ../java/testing/org/apache/derbyTesting/system/nstest.
You used the same basic command as I was - except I redirect the output to a
file, and I had created a derby.properties file with the following:
derby.language.logStatementText=true
derby.stream.error.logSeverityLevel=0
derby.language.sequence.preallocator=200
I always find this test awkward to analyze/debug, but it does do a good amount
of various simple actions to the database. I usually grep for ERROR in the
output, and then ignore (usually using | grep -v) errors seen in previous runs,
such as XBM06, 22003, 40001 (deadlock!), 40XL1.
I did use the sane jars from the original 10.8.2.1 candidate.
This error popped up almost immediately - the nstestdb database has OS time
stamp Sep 20 15:03 and the time stamp for this error in derby.log is Sep 20
15:04:06
Note that this error did not show up in any of my other test runs, with
basically the same source code, except they were insane jars.
So, thank you for looking at the code path and your analysis - your explanation
makes sense.
I will now set up a test run with the head of 10.8. If I don't see this again,
we can close it either as cannot reproduce,
or mark it as a duplicate of DERBY-5389.
> ns test error: ERROR 38000: The exception
> 'org.apache.derby.shared.common.sanity.AssertFailure: ASSERT FAILED Identity
> being changed on a live cacheable. Old uuidString =
> 846c00a0-0132-88d8-fec0-000000080920' was thrown while evaluating an
> expression
> -----------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------
>
> Key: DERBY-5428
> URL: https://issues.apache.org/jira/browse/DERBY-5428
> Project: Derby
> Issue Type: Bug
> Affects Versions: 10.8.2.1
> Environment: Windows XP, ibm 16 sr 9 fp1, with modified
> derbyTesting.jar (ps2.close & print exception reversed as suggested in
> DERBY-5421)
> Reporter: Myrna van Lunteren
>
> In an attempt to reproduce DERBY-5421/ DERBY-5422/ DERBY-5423 and get more
> information, I ran the nstest (embedded on another Windows machine, with the
> same jvm version, but with the following setup:
> - sane jars from the 10.8.2.1 RC2 (rev 1170221)
> - derbyTesting.jar built (sane) at the same level, but with modifications to
> put ps2.close() in Dbutil after the exception print
> - the following details in a derby.properties file:
> derby.language.logStatementText=true
> derby.stream.error.logSeverityLevel=0
> derby.language.sequence.preallocator=200
> This time, so far, I only see the following unexpected error:
> (in derby.log:) ERROR 38000: The exception
> 'org.apache.derby.shared.common.sanity.AssertFailure: ASSERT FAILED Identity
> being changed on a live cacheable. Old uuidString =
> 846c00a0-0132-88d8-fec0-000000080920' was thrown while evaluating an
> expression
> This looks like DERBY-5389, but it has a different stack trace and happens
> with ibm 1.6:
> ------------------------------------------------------------------
> Tue Sep 20 15:04:06 PDT 2011 Thread[Thread 3,5,main] (XID = 58789),
> (SESSIONID = 22), (DATABASE = nstestdb), (DRDAID = null), Begin compiling
> prepared statement: update nstesttab set t_double = ? where serialkey =
> 36027 :End prepared statement
> --
> ERROR 38000: The exception
> 'org.apache.derby.shared.common.sanity.AssertFailure: ASSERT FAILED Identity
> being changed on a live cacheable. Old uuidString =
> 846c00a0-0132-88d8-fec0-000000080920' was thrown while evaluating an
> expression.
> at
> org.apache.derby.iapi.error.StandardException.newException(StandardException.java:296)
> at
> org.apache.derby.iapi.error.StandardException.unexpectedUserException(StandardException.java:583)
> at
> org.apache.derby.impl.services.reflect.DirectCall.invoke(ReflectGeneratedClass.java:164)
> at
> org.apache.derby.impl.sql.execute.RowResultSet.getNextRowCore(RowResultSet.java:148)
> at
> org.apache.derby.impl.sql.execute.NormalizeResultSet.getNextRowCore(NormalizeResultSet.java:185)
> at
> org.apache.derby.impl.sql.execute.DMLWriteResultSet.getNextRowCore(DMLWriteResultSet.java:127)
> at
> org.apache.derby.impl.sql.execute.InsertResultSet.open(InsertResultSet.java:515)
> at
> org.apache.derby.impl.sql.GenericPreparedStatement.executeStmt(GenericPreparedStatement.java:436)
> at
> org.apache.derby.impl.sql.GenericPreparedStatement.execute(GenericPreparedStatement.java:317)
> at
> org.apache.derby.impl.jdbc.EmbedStatement.executeStatement(EmbedStatement.java:1242)
> at
> org.apache.derby.impl.jdbc.EmbedPreparedStatement.executeStatement(EmbedPreparedStatement.java:1686)
> at
> org.apache.derby.impl.jdbc.EmbedPreparedStatement.executeUpdate(EmbedPreparedStatement.java:308)
> at
> org.apache.derbyTesting.system.nstest.utils.DbUtil.add_one_row(DbUtil.java:201)
> at
> org.apache.derbyTesting.system.nstest.tester.TesterObject.doIUDOperation(TesterObject.java:148)
> at
> org.apache.derbyTesting.system.nstest.tester.Tester1.startTesting(Tester1.java:118)
> at org.apache.derbyTesting.system.nstest.NsTest.run(NsTest.java:551)
> Caused by: org.apache.derby.shared.common.sanity.AssertFailure: ASSERT FAILED
> Identity being changed on a live cacheable. Old uuidString =
> 846c00a0-0132-88d8-fec0-000000080920
> at
> org.apache.derby.shared.common.sanity.SanityManager.THROWASSERT(SanityManager.java:162)
> at
> org.apache.derby.shared.common.sanity.SanityManager.THROWASSERT(SanityManager.java:147)
> at
> org.apache.derby.impl.sql.catalog.SequenceUpdater.setIdentity(SequenceUpdater.java:272)
> at
> org.apache.derby.impl.services.cache.ConcurrentCache.find(ConcurrentCache.java:295)
> at
> org.apache.derby.impl.sql.catalog.DataDictionaryImpl.getCurrentValueAndAdvance(DataDictionaryImpl.java:10352)
> at
> org.apache.derby.impl.sql.execute.InsertResultSet.getSetAutoincrementValue(InsertResultSet.java:824)
> at
> org.apache.derby.impl.sql.execute.BaseActivation.getSetAutoincrementValue(BaseActivation.java:666)
> at
> org.apache.derby.exe.ac092d4de3x0132x88d8xfec0x0000000809207.e0(Unknown
> Source)
> at
> org.apache.derby.impl.services.reflect.DirectCall.invoke(ReflectGeneratedClass.java:139)
> ... 13 more
> ============= begin nested exception, level (1) ===========
> org.apache.derby.shared.common.sanity.AssertFailure: ASSERT FAILED Identity
> being changed on a live cacheable. Old uuidString =
> 846c00a0-0132-88d8-fec0-000000080920
> at
> org.apache.derby.shared.common.sanity.SanityManager.THROWASSERT(SanityManager.java:162)
> at
> org.apache.derby.shared.common.sanity.SanityManager.THROWASSERT(SanityManager.java:147)
> at
> org.apache.derby.impl.sql.catalog.SequenceUpdater.setIdentity(SequenceUpdater.java:272)
> at
> org.apache.derby.impl.services.cache.ConcurrentCache.find(ConcurrentCache.java:295)
> at
> org.apache.derby.impl.sql.catalog.DataDictionaryImpl.getCurrentValueAndAdvance(DataDictionaryImpl.java:10352)
> at
> org.apache.derby.impl.sql.execute.InsertResultSet.getSetAutoincrementValue(InsertResultSet.java:824)
> at
> org.apache.derby.impl.sql.execute.BaseActivation.getSetAutoincrementValue(BaseActivation.java:666)
> at
> org.apache.derby.exe.ac092d4de3x0132x88d8xfec0x0000000809207.e0(Unknown
> Source)
> at
> org.apache.derby.impl.services.reflect.DirectCall.invoke(ReflectGeneratedClass.java:139)
> at
> org.apache.derby.impl.sql.execute.RowResultSet.getNextRowCore(RowResultSet.java:148)
> at
> org.apache.derby.impl.sql.execute.NormalizeResultSet.getNextRowCore(NormalizeResultSet.java:185)
> at
> org.apache.derby.impl.sql.execute.DMLWriteResultSet.getNextRowCore(DMLWriteResultSet.java:127)
> at
> org.apache.derby.impl.sql.execute.InsertResultSet.open(InsertResultSet.java:515)
> at
> org.apache.derby.impl.sql.GenericPreparedStatement.executeStmt(GenericPreparedStatement.java:436)
> at
> org.apache.derby.impl.sql.GenericPreparedStatement.execute(GenericPreparedStatement.java:317)
> at
> org.apache.derby.impl.jdbc.EmbedStatement.executeStatement(EmbedStatement.java:1242)
> at
> org.apache.derby.impl.jdbc.EmbedPreparedStatement.executeStatement(EmbedPreparedStatement.java:1686)
> at
> org.apache.derby.impl.jdbc.EmbedPreparedStatement.executeUpdate(EmbedPreparedStatement.java:308)
> at
> org.apache.derbyTesting.system.nstest.utils.DbUtil.add_one_row(DbUtil.java:201)
> at
> org.apache.derbyTesting.system.nstest.tester.TesterObject.doIUDOperation(TesterObject.java:148)
> at
> org.apache.derbyTesting.system.nstest.tester.Tester1.startTesting(Tester1.java:118)
> --
> at
> org.apache.derby.iapi.error.StandardException.newException(StandardException.java:286)
> at org.apache.derby.iapi.types.SQLDecimal.setWidth(SQLDecimal.java:1063)
> at org.apache.derby.iapi.types.SQLDecimal.normalize(SQLDecimal.java:759)
> at
> org.apache.derby.iapi.types.DataTypeDescriptor.normalize(DataTypeDescriptor.java:648)
> at
> org.apache.derby.impl.sql.execute.NormalizeResultSet.normalizeColumn(NormalizeResultSet.java:329)
> at
> org.apache.derby.impl.sql.execute.NormalizeResultSet.normalizeRow(NormalizeResultSet.java:373)
> at
> org.apache.derby.impl.sql.execute.NormalizeResultSet.getNextRowCore(NormalizeResultSet.java:188)
> at
> org.apache.derby.impl.sql.execute.DMLWriteResultSet.getNextRowCore(DMLWriteResultSet.java:127)
> at
> org.apache.derby.impl.sql.execute.UpdateResultSet.collectAffectedRows(UpdateResultSet.java:440)
> at
> org.apache.derby.impl.sql.execute.UpdateResultSet.open(UpdateResultSet.java:264)
> at
> org.apache.derby.impl.sql.GenericPreparedStatement.executeStmt(GenericPreparedStatement.java:436)
> at
> org.apache.derby.impl.sql.GenericPreparedStatement.execute(GenericPreparedStatement.java:317)
> at
> org.apache.derby.impl.jdbc.EmbedStatement.executeStatement(EmbedStatement.java:1242)
> at
> org.apache.derby.impl.jdbc.EmbedPreparedStatement.executeStatement(EmbedPreparedStatement.java:1686)
> at
> org.apache.derby.impl.jdbc.EmbedPreparedStatement.executeUpdate(EmbedPreparedStatement.java:308)
> at
> org.apache.derbyTesting.system.nstest.utils.DbUtil.update_one_row(DbUtil.java:382)
> at
> org.apache.derbyTesting.system.nstest.tester.TesterObject.doIUDOperation(TesterObject.java:162)
> at
> org.apache.derbyTesting.system.nstest.tester.Tester1.startTesting(Tester1.java:118)
> at org.apache.derbyTesting.system.nstest.NsTest.run(NsTest.java:551)
--
This message is automatically generated by JIRA.
For more information on JIRA, see: http://www.atlassian.com/software/jira
