MySQL连接抛出Authentication Failed错误的示例分析

58次阅读
没有评论

共计 4002 个字符,预计需要花费 11 分钟才能阅读完成。

这篇文章主要为大家展示了“MySQL 连接抛出 Authentication Failed 错误的示例分析”,内容简而易懂,条理清晰,希望能够帮助大家解决疑惑,下面让丸趣 TV 小编带领大家一起研究并学习一下“MySQL 连接抛出 Authentication Failed 错误的示例分析”这篇文章吧。

【问题描述】

在应用端,偶尔看到有如下报错:

Authentication to host xxxx for user yyyy using method mysql_native_password failed with message: Reading from the stream has failed.

表现特征:

    1. 只有用 Connector/NET 出现这个问题,用 JDBC 驱动没有类似问题。

    2. 多台应用服务器,只有一台报这个错,因此可以排除服务器端的问题。

    3. 问题非常随机,重启一下服务器 /IIS,就能临时解决问题。

    4. 有一些场景应用服务器 CPU 并不是很高,也会偶尔抛出这个错来。

客户端是 Windows 机器,驱动是 MySQL Connector ADO.NET Driver for MySQL (Connector/NET),使用的版本是 6.9.9,是比较新的版本。

下面话不多说了,来一起看看详细的分析与解决思路吧

【问题分析】

我们在应用服务器端和数据库端抓包。两边抓到的包是一致的。可以排除网络问题。下面是抓到的包,以及时间点:

编号绝对时间相对时间 (秒) 源头目的网络包内容  1    12:58:47    9.07    应用服务器    数据库服务器    ……S.    2    12:58:47    9.07    数据库服务器    应用服务器     …A..S.     3    12:58:47    9:07    应用服务器    数据库服务器    …A….     4    12:58:47    9:07    数据库服务器    应用服务器     …AP…     5    12:58:47    9.27    应用服务器    数据库服务器    …A….     6    12:58:57    19.12    数据库服务器    应用服务器      …A…F     7    12:58:57    19.12    应用服务器    数据库服务器    …A….     8    12:59:10    32.00    应用服务器    数据库服务器    …AP…     9    12:59:10    32.00    数据库服务器    应用服务器     …..R..   

从上述网络包的交互来看,前面三个包是 TCP 的三次握手协议。问题出在第六个包,数据库服务器向应用服务器发送了一个 Finish 包,来终止数据库的连接。数据库发送 Finish 包,是由于数据库端发现连接超时而发送的。这是由服务器端的 Connect_timeout 这个变量来控制。原因在于应用端超过 10 秒未向数据库服务器端发送网络包。从网络包交互的情况来看,第五个包和第六个包的时间间隔刚好是 10 秒。

对比正常的数据库连接和上面异常的数据库连接。应用服务器发送第 5 个包到数据库端后,应该紧接着发送下面的网络包到数据库端的。这个包主要是发送账号,驱动版本,操作系统信息等到数据库服务器端。【下面是部分的正常的网络包截图】。在出现异常报错的场景,客户端是延迟发送这个包的。在 Frame 8 才发送的。而此时连接已经被 Finish 了,在 Frame 9,数据库端发送了一个 Reset 包到应用服务器,彻底中断连接。

我们现在具体分析,为何客户端发送账号,驱动版本,操作系统信息到数据库端这么慢。这部分的代码在 Connector/NET MySQLAuthenticationPlugin.cs 文件中。我们修改这部分代码,进行时间埋点,来进一步定位问题。下面是根据时间埋点,打印出来的跟踪信息。

从跟踪的 Trace 来看,有 30 秒左右的操作延时。返回 MySQLDefs::OSDetails 的时候。这部分代码如下:

[DisplayName( _os_details)]
public string OSDetails
string os = string.Empty;
var searcher = new System.Management.ManagementObjectSearcher( SELECT * FROM Win32_OperatingSystem 
var collection = searcher.Get();
foreach (var mgtObj in collection)
os = mgtObj.GetPropertyValue(Caption).ToString();
break;
catch (Exception ex) { System.Diagnostics.Debug.WriteLine(ex.ToString()); }
return os;
}

这段代码是通过 WMI 查询,来获得 Caption 信息。也就是操作系统的版本信息。由于是 WMI 调用,所以依赖的关系比较多。

【问题验证】

我们把这段代码抽出来。下面是一段简短的 Repro 代码:

static void Main(string[] args)
Stopwatch watch = new Stopwatch();
while (true)
watch.Restart();
var searcher = new System.Management.ManagementObjectSearcher( SELECT * FROM Win32_OperatingSystem 
var collection = searcher.Get();
foreach (var mgtObj in collection)
string os = mgtObj.GetPropertyValue(Caption).ToString();
watch.Stop();
Console.WriteLine(watch.ElapsedMilliseconds);
if (watch.ElapsedMilliseconds  = 1000)
Console.WriteLine( ------------- 
File.AppendAllText(abc.txt , DateTime.Now.ToString( yyyy-MM-dd HH:mm:ss.fff) + , + watch.ElapsedMilliseconds +  \r\n 
}

在有问题的应用服务器上,我们运行上述代码,确实可以发现 WMI 查询有超时:下面这些点是我们抓到的超过 30 秒的点:

2017-11-21 17:19:30.208,33638

2017-11-21 17:20:09.193,33199

2017-11-21 17:20:53.086,33201

2017-11-21 17:27:05.114,32976

2017-11-21 17:28:19.178,33635

2017-11-21 17:30:07.130,65977

2017-11-21 17:30:49.051,40478

2017-11-21 17:31:15.126,26072

2017-11-21 17:38:16.048,66671

2017-11-21 17:38:49.204,33152

2017-11-21 17:39:53.161,33828

2017-11-21 17:40:38.121,33549

2017-11-21 17:47:09.179,33775

2017-11-21 17:47:57.174,33164

【解决思路】

WMI 查询慢,可能是由于多种原因所致。如操作系统 CPU 高,或者查询本身有死锁。这个问题有待于进一步分析。但看代码,我们知道做这个 WMI 查询,只是为了获得操作系统的信息。这个信息完全可以缓存起来。而不必要每次连接的时候,去进行 WMI 查询。

此处确定该错误的根本原因在于 MySQL 的 C# connector 中对操作系统信息的获取时间过久,导致触发服务器的连接超时。注释掉该部分(可能导致长时间的操作),进行进一步的验证,再无任何的超时错误出现。

public string OSDetails
dbglog.dolog( MysqlDefs::OSDetails1 
string os = string.Empty;
/*try
var searcher = new System.Management.ManagementObjectSearcher( SELECT * FROM Win32_OperatingSystem 
var collection = searcher.Get();
foreach (var mgtObj in collection)
os = mgtObj.GetPropertyValue(Caption).ToString();
dbglog.dolog(String.Format( MysqlDefs::OSDetails::foreach{0} , os.ToString()));
break;
catch (Exception ex) { System.Diagnostics.Debug.WriteLine(ex.ToString()); }*/
dbglog.dolog( MysqlDefs::OSDetails2 
return os;
}

以上是“MySQL 连接抛出 Authentication Failed 错误的示例分析”这篇文章的所有内容,感谢各位的阅读!相信大家都有了一定的了解,希望分享的内容对大家有所帮助,如果还想学习更多知识,欢迎关注丸趣 TV 行业资讯频道!

正文完
 
丸趣
版权声明:本站原创文章,由 丸趣 2023-08-04发表,共计4002字。
转载说明:除特殊说明外本站除技术相关以外文章皆由网络搜集发布,转载请注明出处。
评论(没有评论)