我们在应用程序中遇到以下异常:
执行超时已过期。在操作完成之前超时期限已过,或者服务器没有响应。
所以,我查看了查询,果然花了很长时间。所以我把它调到不到一秒钟。
但我仍然超时。
因此,我为 Attention 事件设置了一个扩展事件会话以捕获超时并查看发生了什么(使用此页面作为指南)。
结果令人困惑。我在列表中看到了我的查询,但跟踪中记录的持续时间不到一秒!
以下是我在超时持续时间列中获得的一些示例值:
暂停 |
---|
539 |
539 |
474 |
423 |
505 |
904 |
我很困惑。 当持续时间平均显示半秒时,为什么我会收到 SQL Server 超时错误?
注意:扩展事件的输出显示一些行超过 30 秒。在我看来,这些是合法的超时,我正在努力解决这些问题。(我只是指出这一点以表明Attention扩展事件似乎对这些查询有效。)
我认为它可能会阻塞,所以我设置了一个Blocked Process Report扩展事件来查看(遵循本指南)。虽然服务器上发生了阻塞,但它们不在我的查询所针对的数据库上。
该应用程序使用称为 Dapper 的 API(我听说这是一种相当流行的 Micro ORM,用于运行 Stack Exchange)。我想它有不错的连接管理。但即使他们滥用其连接,据我所知,这也会导致资源泄漏,而不是 SQL 超时。
应用程序中的查询超时设置为 120 秒。这应该有足够的时间让我看到的大多数查询报告为超时。
我已经意识到,无论持续时间是多少,都不是超时查询运行的时间。我
waitfor delay '00:00:30'
在一个设置为 10 秒超时的查询窗口中运行。所有超时的结果应该是 10 秒。但是我的 6 个测试得到了这些值:70707、311、269、264、3442、272。我的猜测是持续时间是处理 Attention 事件(即停止查询)所花费的时间。只是为了确保持续时间不会与实际运行的查询一起正常工作,我运行了一个长时间运行的查询,再次设置为 10 秒后超时。其中的持续时间:64246、58002、52828、68061、48153、49084。他们花了更长的时间,但这些时间都没有接近 10 秒。再次确认持续时间不是它看起来的样子。
文档说持续时间以微秒(而不是毫秒)为单位。如果到了处理 Attention 事件的时间,这也可以工作,但它永远不会匹配超时查询运行所花费的时间。
我在没有超时的情况下运行了长时间运行的查询,并且不得不在 120 秒后停止它。停止花费的时间更长,Attention 事件的持续时间为 6400055。6.4 秒大约是停止花费的时间。再次确认持续时间是处理“注意”事件所需的时间。