[DERBY-5564] Code does different things depending if derby.locks.deadlockTrace=true is set

• Type: Bug
• Status: Closed
• Priority: Major
• Resolution: Fixed
• Affects Version/s: 10.1.3.1, 10.2.2.0, 10.3.3.0, 10.4.2.0, 10.5.3.0, 10.6.2.1, 10.7.1.1, 10.8.2.2
• Fix Version/s: 10.5.3.2, 10.6.2.4, 10.7.1.4, 10.8.3.0, 10.9.1.0
• Component/s: Network Client, Network Server
• Environment: Solaris 10; Glassfish V2.1.1
• Issue & fix info: High Value Fix, Patch Available, Release Note Needed
• Assignee: Mike Matrigali
• Reporter: Brett Bergquist
• Created: 03/Jan/12 14:40
• Updated: 15/Nov/13 08:15
• Resolved: 23/Jan/12 20:31

Description

I see a problem in the code handling lock timeout exceptions. In the code in various places there are calls such as:

// 2 kinds of errors here expected here. Either container not
// found or could not obtain lock (LOCK_TIMEOUT or DEADLOCK).
//
// It is possible by the time this post commit work gets scheduled
// that the container has been dropped and that the open container
// call will return null - in this case just return assuming no
// work to be done.

if (se.getMessageId().equals(SQLState.LOCK_TIMEOUT) ||
se.getMessageId().equals(SQLState.DEADLOCK))

Or

// First try to do the work in the nested transaction. Fail if we can't
// get a lock immediately.
if ( nestedTransaction != null )
{
try

{ return updateCurrentValueOnDisk( nestedTransaction, oldValue, newValue, false ); }

catch (StandardException se)
{
if ( !se.getMessageId().equals( SQLState.LOCK_TIMEOUT ) )

{ throw se; }

}

Or

// exception might have occured either container got dropper or lock not granted.
// It is possible by the time this post commit work gets scheduled
// that the container has been dropped and that the open container
// call will return null - in this case just return assuming no
// work to be done.

//If this expcetion is because lock could not be obtained , work is requeued.
if (se.getMessageId().equals(SQLState.LOCK_TIMEOUT) ||
se.getMessageId().equals(SQLState.DEADLOCK))

{ requeue_work = true; }

The problem that I see is that if the property "derby.locks.deadlockTrace=true" is set, then instead of a SQLState.LOCK_TIMEOUT, the code will see a SQLState.LOCK_TIMEOUT_LOG. Note that this is not being checked for in the above tests and others as well, so now the code behavior is going to change basd on whether the lock tracing is enabled or not.

I think that 99% of the code that is testing for SQLState.LOCK_TIMEOUT should also be checking for SQLState.LOCK_TIMEOUT_LOG as well. I only see one place in DDLConstantAction where it is explicitly mentioned that SQLState.LOCK_TIMEOUT_LOG is not being looked at.

Issue Links

• is related to: DERBY-5961 Developer's guide mentions SQL state 40XL2 which is no longer used