We had a report of crashes occurring for certain users when accessing a system. From the stack data in the production logs, a timeout was occurring when running a specific stored procedure. This procedure was written around 5 years ago and is in use in many customer databases without issue. Why would the same SQL suddenly start timing out in one particular database?
The stored procedure in question is called for users with certain permissions to highlight outstanding units of work that their access level permits them to do, and is a fairly popular (and useful) feature of the software.
After obtaining session information from the crash logs, it was time to run the procedure on a copy of the live database with session details. The procedure only reads information, but doing this on a copy helps ensure no ... accidents occur.
And it took... 27 seconds to return 13 rows. Not good, not good at all.
Viewing the query plan showed something interesting though - one of the nodes was flagged with a warning symbol, and when the mouse was hovered over it it stated
Type conversion in expression (CONVERT_IMPLICIT(nvarchar(50),[Pn].[SiteId],0)) may affect "CardinalityEstimate" in query plan choice
Time to check the procedure's SQL as there shouldn't actually be any conversions being done, let alone implicit ones.
I can't publish the full SQL in this blog, so I've chopped out all the table names and field names and used dummy aliases. The important bits for the purposes of this post are present though, although I apologize that it's less than readable now.
The SQL is fairly straight forward - we join a bunch of
different data tables together based on permissions, data status
and where the [SiteId]
column matches the lookup value, return
return a unique list of core identifiers. With the exception of
[SiteId]
all those joins on [Id]
columns are integers.
Yes,
[SiteId]
is the primary key in a table. Yes, I know it isn't a good idea using string keys. It was a design decision made over 8 years ago and I'm sure at some point these anomalies will be changed. But it's a side issue to what this post is about.
As the warning from the query plan is quite explicit about the
column it's complaining about, it is now time to check the
definition of the table containing the [SiteId]
column. Again,
I'm not at liberty to include anything other than the barest
information to show the problem.
Can you see the problem? The table defines [SiteId]
as
varchar(50)
- that is, up to 50 ASCII characters. The stored
procedure on the other hand defines the @strSiteId
parameter
(that is used as a WHERE
clause for [SiteId]
) as
nvarchar(50)
, i.e. up to 50 Unicode characters. And there we
go, implicit conversion from Unicode to ASCII that for some
(still unknown at this stage) reason destroyed the performance
of this particular database.
After changing the stored procedure (remember I'm on a copy of
the production database!) to remove that innocuous looking n
,
I reran the procedure which completed instantly. And the warning
has disappeared from the plan.
The error probably originally occurred as a simple oversight -
almost all character fields in the database are nvarchar
's.
Those that are varchar
are ones that control definition data
that cannot be entered, changed or often even viewed by end
users. Anything that the end user can input is always nvarchar
due to the global nature of the software in question.
Luckily, it's a simple fix, although potentially easy to miss, especially as you might immediately assume the SQL itself is to blame and try to optimize that.
The take away from this story is simple - ensure that the data types for variables you use in SQL match the data types of the fields to avoid implicit conversions that can cause some very unexpected and unwelcome performance issues - even years after you originally wrote the code.
Update History
- 2016-05-06 - First published
- 2020-11-21 - Updated formatting
Like what you're reading? Perhaps you like to buy us a coffee?