編號 | 絕對時間 | 相對時間(秒) | 源頭 | 目的 | 網(wǎng)絡(luò)包內(nèi)容 |
---|---|---|---|---|---|
1 | 12:58:47 | 9.07 | 應(yīng)用服務(wù)器 | 數(shù)據(jù)庫服務(wù)器 | ......S. |
2 | 12:58:47 | 9.07 | 數(shù)據(jù)庫服務(wù)器 | 應(yīng)用服務(wù)器 | …A..S. |
3 | 12:58:47 | 9:07 | 應(yīng)用服務(wù)器 | 數(shù)據(jù)庫服務(wù)器 | …A…. |
4 | 12:58:47 | 9:07 | 數(shù)據(jù)庫服務(wù)器 | 應(yīng)用服務(wù)器 | …AP… |
5 | 12:58:47 | 9.27 | 應(yīng)用服務(wù)器 | 數(shù)據(jù)庫服務(wù)器 | …A…. |
6 | 12:58:57 | 19.12 | 數(shù)據(jù)庫服務(wù)器 | 應(yīng)用服務(wù)器 | …A…F |
7 | 12:58:57 | 19.12 | 應(yīng)用服務(wù)器 | 數(shù)據(jù)庫服務(wù)器 | …A…. |
8 | 12:59:10 | 32.00 | 應(yīng)用服務(wù)器 | 數(shù)據(jù)庫服務(wù)器 | …AP… |
9 | 12:59:10 | 32.00 | 數(shù)據(jù)庫服務(wù)器 | 應(yīng)用服務(wù)器 | …..R.. |
從上述網(wǎng)絡(luò)包的交互來看, 前面三個包是TCP的三次握手協(xié)議。問題出在第六個包,數(shù)據(jù)庫服務(wù)器向應(yīng)用服務(wù)器發(fā)送了一個Finish包,來終止數(shù)據(jù)庫的連接。數(shù)據(jù)庫發(fā)送Finish包,是由于數(shù)據(jù)庫端發(fā)現(xiàn)連接超時而發(fā)送的。 這是由服務(wù)器端的Connect_timeout這個變量來控制。原因在于應(yīng)用端超過10秒未向數(shù)據(jù)庫服務(wù)器端發(fā)送網(wǎng)絡(luò)包。從網(wǎng)絡(luò)包交互的情況來看,第五個包和第六個包的時間間隔剛好是10秒。
對比正常的數(shù)據(jù)庫連接和上面異常的數(shù)據(jù)庫連接。 應(yīng)用服務(wù)器發(fā)送第5個包到數(shù)據(jù)庫端后, 應(yīng)該緊接著發(fā)送下面的網(wǎng)絡(luò)包到數(shù)據(jù)庫端的。這個包主要是發(fā)送賬號,驅(qū)動版本,操作系統(tǒng)信息等到數(shù)據(jù)庫服務(wù)器端?!鞠旅媸遣糠值恼5木W(wǎng)絡(luò)包截圖】。在出現(xiàn)異常報錯的場景,客戶端是延遲發(fā)送這個包的。在Frame 8才發(fā)送的。而此時連接已經(jīng)被Finish了,在Frame 9,數(shù)據(jù)庫端發(fā)送了一個Reset包到應(yīng)用服務(wù)器,徹底中斷連接。
我們現(xiàn)在具體分析,為何客戶端發(fā)送賬號,驅(qū)動版本,操作系統(tǒng)信息到數(shù)據(jù)庫端這么慢。這部分的代碼在Connector/NET MySQLAuthenticationPlugin.cs文件中。 我們修改這部分代碼,進(jìn)行時間埋點,來進(jìn)一步定位問題。下面是根據(jù)時間埋點,打印出來的跟蹤信息。
從跟蹤的Trace來看,有30秒左右的操作延時。返回MySQLDefs::OSDetails的時候。這部分代碼如下:
[DisplayName("_os_details")] public string OSDetails { get { 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(); break; } } catch (Exception ex) { System.Diagnostics.Debug.WriteLine(ex.ToString()); } return os; } }
這段代碼是通過WMI查詢,來獲得Caption信息。也就是操作系統(tǒng)的版本信息。由于是WMI調(diào)用,所以依賴的關(guān)系比較多。
【問題驗證】
我們把這段代碼抽出來。下面是一段簡短的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"); } } }
在有問題的應(yīng)用服務(wù)器上,我們運行上述代碼,確實可以發(fā)現(xià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查詢慢,可能是由于多種原因所致。如操作系統(tǒng)CPU高,或者查詢本身有死鎖。這個問題有待于進(jìn)一步分析。但看代碼,我們知道做這個WMI查詢,只是為了獲得操作系統(tǒng)的信息。這個信息完全可以緩存起來。而不必要每次連接的時候,去進(jìn)行WMI查詢。
此處確定該錯誤的根本原因在于MySQL的C# connector中對操作系統(tǒng)信息的獲取時間過久,導(dǎo)致觸發(fā)服務(wù)器的連接超時。注釋掉該部分(可能導(dǎo)致長時間的操作),進(jìn)行進(jìn)一步的驗證,再無任何的超時錯誤出現(xiàn)。
public string OSDetails { get { 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; } }
總結(jié)
以上就是這篇文章的全部內(nèi)容了,希望本文的內(nèi)容對大家的學(xué)習(xí)或者工作具有一定的參考學(xué)習(xí)價值,如果有疑問大家可以留言交流,謝謝大家對腳本之家的支持。
標(biāo)簽:賀州 宿遷 南充 黔南 蘭州 馬鞍山 佛山 黃山
巨人網(wǎng)絡(luò)通訊聲明:本文標(biāo)題《MySQL連接拋出Authentication Failed錯誤的分析與解決思路》,本文關(guān)鍵詞 MySQL,連接,拋出,Authentication,;如發(fā)現(xiàn)本文內(nèi)容存在版權(quán)問題,煩請?zhí)峁┫嚓P(guān)信息告之我們,我們將及時溝通與處理。本站內(nèi)容系統(tǒng)采集于網(wǎng)絡(luò),涉及言論、版權(quán)與本站無關(guān)。