下单快发货慢:一个 JOIN SQL 引起 SqlClient 读取数据慢的奇特问题

  • 时间:
  • 浏览:2
  • 来源:大发pk10_pk10走势图_大发pk10走势图

更新:这名问提报告 是 System.Data.SqlClient 的有2个多 bug 引起的,详见 坑暗花明:又遇 .NET Core 中 System.Data.SqlClient 查询缓慢的问提报告

最近遇到有2个多 非常奇特的问提报告 ,在有2个多 ASP.NET Core 项目中从 SQL Server 60 8 R2 中查询获取 60 条记录竟然耗时 10 多秒,原因分析 是查询并全部都是慢,那到全部都是这名奇特的问提报告 。

说它非常奇特原因分析 耗时主要存在在 SqlDataReader 读取数据时

2019-04-04 21:31:58.546 [Information] Executed DbCommand ("2,656"ms)
...
2019-04-04 21:32:10.690 [Debug] A data reader was disposed.

进一步测试发现

查询获取 1 条数据库记录,耗时在 260

ms 左右  
查询获取 10 条数据库记录,耗时在 1.6s-2s 之间
查询获取 60

 条数据库记录,耗时在 12s-22s 之间

开始英文怀疑是 EF Core 的问提报告 ,通过在 EF Core 源码中打点,定位到耗时存在在 _dataReader.ReadAsync 处

while (await _dataReader.ReadAsync(cancellationToken))
{
    _buffer.Enqueue(_valueBufferFactory.Create(_dbDataReader));
}

_dataReader.ReadAsync 实际调用的是 System.Data.SqlClient 中的 SqlDataReader.ReadAsync 措施。

一次 ReadAsync 读取一行记录,通过在 SqlClient 的源代码中打点记录时间戳发现,在 60 次一行一行读取中,其含高有2个读取会总出 延迟,比如某一次 13 秒延迟,60 次读取中总出 了 5 次读取延迟 —— 2s + 3s + 3s + 2s + 3s = 13s 。

经过在 System.Data.SqlClient 源代码中无数次打点记录时间戳最终定位到延迟存在在  SNIPacket.ReadFromStreamAsync()  措施中  stream.ReadAsync()  时

Console.WriteLine($"Entering stream.ReadAsync() at {DateTime.Now}");
stream.ReadAsync(_data, 0, _capacity, CancellationToken.None).ContinueWith(t =>
{
    Console.WriteLine($"stream.ReadAsync().ContinueWith at {DateTime.Now}");
    //...
}

stream 对应的是 NetworkStream ,延迟存在在网络传输过程中,与 SqlClient 没关系。

TCP 抓包发现 SQL Server 服务器发送的数据到达就延迟了。

于是不能将怀疑对象锁定在 SQL Server 数据库层面。

对应的 SQL 查询搞笑的话涉及 4 张表,FROM 一张表(表A), JOIN 三张表(LEFT JOIN 表B,LEFT JOIN 表C ,INNER JOIN 表D),表A有60 0多万条记录,表C有60 0多万条记录,查询时按表A的主键排序,表A的聚集索引建在时间字段上,那么建在主键上。

SELECT ...
FROM TableA
LEFT JOIN TableB ON [TableA].[Id] = [TableB].[EntryID]
LEFT JOIN TableC ON [TableA].[Id] = [TableC].[EntryID]
INNER JOIN TableD  ON [TableA].[BlogID] = [TableD].[BlogID]
WHERE ([TableA].[Id] >= @__startId_0)

并全部都是所有查询都总出 这名问提报告 ,当 @__startId_0 小于一定值都会总出 。

如果尝试将  LEFT JOIN TableC 改为 INNER JOIN TableC ,问提报告 竟然消失了,但进一步测试发现当  @__startId_0  再小到一定值问提报告 又会总出 。

既然问提报告 与 JOIN TableC 有关,那干脆不进行 JOIN ,单独查询 TableC ,或者将在 C# 代码中将查询的结果合并进行,有2个多 改进了,查询获取 60 条记录只需 60 多毫秒。

这名奇特的问提报告 就有2个多 用有2个多 简单粗暴有效的措施临时避免了。

对于这名问提报告 的根本原因分析 ,怀疑与 TableA 那么把聚集索引建在 Id 字段上有关,但目前那么修改聚集索引进行验证,以前再找原因分析 验证。