坑暗花明:又遇 .NET Core 中 System.Data.SqlClient 查询缓慢的问题

  • 时间:
  • 浏览:0
  • 来源:大发pk10_pk10在线注册平台_大发pk10在线注册平台

完后 发布过一篇博文 下单快发货慢:一个多多 JOIN SQL 引起 SqlClient 读取数据慢的奇特什么的大问题,当时遇到的什么的大问题是从 SQL Server 30008 R2 中查询获取 3000 条记录竟然耗时 10 多秒,排查中发现什么的大问题与 SQL  查询饱含 晒 INNER JOIN 有关,再加 INNER JOIN 立马查询太快。当时天真地以为原因分析是 JOIN 的那张表数据记录没法来太少以及主表聚集索引不合理,于是采用将 INNER JOIN 次要拆分出来单独查询临时处理了什么的大问题。

这三三二天一点人又在另外一个多多 ASP.NET Core 2.2 项目中也遇到了查询 3000 数据库记录效率慢(都要4~6秒)的什么的大问题,但会 所查询的数据库表数据量固然是很大。通过 EF Core 的 日志记录发现耗时位于在 Executed DbCommand 时,耗时位于点与完后 的清况 不一样,完后 是位于在 Executed DbCommand 完后  SqlDataReader 从数据库读取数据时。

2019-05-11T14:21:38.1015229+08:00 [INFORMATION] Executed DbCommand ("5,83000"ms)

而通过 SQL Server Management Studio 执行同样的 SQL 励志的话 只都要 20 毫秒左右,相差3000多倍。

Executed DbCommand 日志记录的是 dbCommand.ExecuteReaderAsync 执行的时间(详见 EF Core 的源码)

result = new RelationalDataReader(
    connection,
    dbCommand,
    await dbCommand.ExecuteReaderAsync(cancellationToken),
    commandId,
    Logger);

dbCommand.ExecuteReaderAsync 的实现代码在 corefx 的 System.Data.SqlClient 中,原因分析是 .net core 的什么的大问题,那什么的大问题就出在 System.Data.SqlClient 。

在上次排查 SqlDataReader 读取数据效率慢什么的大问题,就曾怀疑 System.Data.SqlClient ,花了一点一点时间在 System.Data.SqlClient 的源码中打点排查,最终没法找到线索,这次不敢轻易怀疑它。

人太好这次的耗时位于点与上次不一样,但这次的 SQL 查询励志的话 饱含 个地方和上次是一样的,也饱含 INNER JOIN 查询,于是试着再加 INNER JOIN ,Executed DbCommand 只需2毫秒。

[INFORMATION] Executed DbCommand ("2"ms)

啊,缘何也与 INNER JOIN 有关,没道理啊,这次 JOIN 的表数据量不大,全部不原因分析造成 3000 多倍的性能之差。看来上次归罪于 INNER JOIN ,原因分析是冤枉它了,得重新思考与排查这名什么的大问题。

因缘何的大问题是在某个时间点完后 再次出先,于是采取笨最好的办法,回退 git 提交历史直至什么的大问题消失。。。

最终发现,竟然是在一次 git commit 中给这名查询在 SELECT 时增加了一个多多字段引起的, 再加这名字段,什么的大问题立马消失。进一步测试发现,假若任意再加 SELECT 中的一个多多字段,就无需再次出先什么的大问题,太奇怪了。数了数 SELECT 饱含 20个字段,难道与 SELECT 字段的数量有关?完后 的项目会无需也与 SELECT 字段的数量有关?

于是回到完后 的项目,恢复 INNER JOIN 查询,这时惊讶地发现 SqlDataReader 读取数据效率慢的什么的大问题竟然消失了。回想当时处理什么的大问题后到现在所做的变更,唯一的变更一点一点从 .NET Core 3.0 Preivew 4 升级到 .NET Core 3.0 Preivew 5 ,难道 3.0 Preivew 5 把这名什么的大问题给修复了?难道真的是 System.Data.SqlClient 的 bug ?

答案很容易验证,将当前遇到 Executed DbCommand 执行慢的项目升级到 .NET Core 3.0 Preivew 5 ,昨天晚上完成升级后

Executed DbCommand ("3"ms)

飞流直下三千尺,从4秒骤降到3毫秒,相差30000多倍!居然是 System.Data.SqlClient 的一个多多大 bug ,一个多多潜藏你要(最少从 .NET Core 2.1 到 3.0 Preview 4)的巨坑。

这名诡异什么的大问题的谜底在偶然间终于被解开了,这时又产生了新的什么的大问题 —— corefx 中是怎么才能 才能 修复这名巨坑 bug 的?

查看 corefx 中与 System.Data.SqlClient 相关的 git commits ,目测发现下面的 commit (对应的 PR ),你爱不爱我是这名 commit 修复的,接下来找时间验证一下。