VS2012自帶的性能分析工具使用實例

賈朋亮博客 2013-04-24

展開全文

先看console代碼(源代碼下載)：

static void Main(string[] args)
        {
            int i = 10000;
            while(i-->0)
            {
                Core c=new  Core();
                c.Process(DateTime.Now.ToString());
            }
        }
public class Core
    {
        public void Process(string input)
        {
            //process logic
            string result = string.Format("{0}-{1}", DateTime.Now, input);

            //log to file
            Log(result);
        }

        public void Log(string message)
        {
            string fileName = System.IO.Path.Combine(AppDomain.CurrentDomain.BaseDirectory, "log.txt");

            string msg = "{Now}: {Message}";
            msg = msg.Replace("{Now}", DateTime.Now.ToString("yyyy-MM-dd HH:mm:ss"));
            msg = msg.Replace("{Message}", message);
            using (StreamWriter sw = System.IO.File.AppendText(fileName))
            {
                sw.WriteLine(msg);
                sw.Flush();
                sw.Close();
            }
        }
    }

進行初次分析性能，操作步驟如下：

點擊“完成”按鈕，此時會自動進行分析，直接跑完控制臺程序，vs2012會自動顯示出分析報告，如下：

從上面的“摘要”報告中得知

System.IO.File.AppendText函數(shù)和System.AppDomain.get_BaseDirectory函數(shù)耗時最多，我們需要先優(yōu)化這2個函數(shù)的使用（稍后再講）
共列出了最耗時的5個函數(shù)

報告視圖種類：

生成的報告視圖有很多種，上面所示默認的是“摘要”視圖，其他類型的視圖如下所列：

那么我們如何跟蹤這幾個耗時的函數(shù)呢？我們需要轉入“函數(shù)詳細信息”視圖，如下：

右邊紅框代表耗時分布比例，顯然上圖中Process函數(shù)占用了很大比例

下邊的紅框代表相應的代碼，并且還會紅色高亮性能損耗突出的代碼行、以及相應損耗比例（圖中的99.2%是由于這行代碼共有3個損耗點：83.3%+14.4%+1.5%）

我們需要往下跟蹤，進入最嚴重的process函數(shù)進行查看，我們click右邊紅框中的Process條，進入細化的分析界面，如下：

看來主要問題來自"Log(result)"代碼行，占了73.5%，繼續(xù)深入跟蹤，如下圖：

這下差不多了，這里比較損耗多的2行是：獲得fileName和AppendText到日志文件代碼行，分別優(yōu)化：

獲得文件名優(yōu)化
- 日志文件名是不變的，因此不用每次Log(msg)都要計算，直接extract為static的fileName變量
AppendText優(yōu)化
- 一旦牽涉到I/O操作，速度就慢，無法避免（從單行代碼的角度來看），那怎么辦？那就把結構改掉吧：
  - 主程序寫入log到隊列中，由另外一個thread負責寫入到磁盤中

　　修改代碼如下：　　

public class Core
    {
        public void Process(string input)
        {
            //process logic
            string result = string.Format("{0}-{1}", DateTime.Now, input);

            //log to file
            Log(result);
        }


        private static List<string> log = new List<string>();
        public static void Log(string message)//fileName去掉了，因為此時已經(jīng)不需要這個變量了，因為是由其他線程負責寫入磁盤
        {
            string msg = "{Now}: {Message}";
            msg = msg.Replace("{Now}", DateTime.Now.ToString("yyyy-MM-dd HH:mm:ss"));
            msg = msg.Replace("{Message}", message);
            log.Add(msg);
        }

    }