注:本文仅针对 DiscuzNT3.0, sqlserver 2000版本,其他版本请勿对号入座。

在本系列的第(4)篇中,我们提到过windbg,上次没能华丽登场的他,今天终于要一展才华了。解决问题是windbg与生俱来的功夫,他今天将怎么样来解决我们的问题,静听分解。

 

经过第(4)篇的优化之后,我们的论坛迎来了每日100万pv的突破性指标,相对于我们其他项目来说,这个量虽然不值一提,但毕竟这个项目是一切从0开始,能达到这个量也算是一个小突破,值得小庆祝一下。不过问题也随之而来了。我们的iis服务器的cpu占用率从之前的约30%,上升到了80%,按照我们的经验,这是不合理的,80%已经是一个危险的信号,随时都有可能引发server too busy。

 

从公司的利益角度来考虑,为了节约服务器资源和成本,当然要把cpu降下来;从技术人员的角度来考虑,如果你之前没解决过,这将是对自己的一个挑战,如果你解决了,就刚好是你价值的体现,正所谓乱世出英雄,这是一样的道理。好了,废话不多说,有请我们的今日之星windbg。  

 

关于windbg,网上有很多介绍,我就不再累述了。我只发表自己对他的两点认识,比较通俗:

1)可以抓取内存包,即dump文件;

2)通过命令输出内存中正在执行的动作;

第3)步是技术人员自己要做的事情,通过命令输出的信息,分析找出问题的症结。这一步windbg是帮不了你的。

 

网上关于windbg的最常解决的问题一般是

1)程序崩溃;

2)程序挂起,比如死锁,超时;

3)高cpu;

4)内存泄露;

我们今天就来看看他是怎么找到 3)高cpu 的问题的。

 

windbg定位高cpu一般常用的方法和步骤是:

1)在高cpu的时候间隔约5秒钟抓一个包,抓3个左右,用adplus 命令;

2)通过 !runaway 命令找出每个包的前几个线程(约10个);

3)然后找出在每次的前10个线程里都出现的线程id;

4)通过 !clrstack 命名输出该线程的执行动作;

5)通过 第 4) 输出的信息找出问题的症结 

(这是网上很多文章的说法,我觉得说的很模糊);

 

关于第5)点,我提点自己的看法,我一般就看这些个线程id都在干什么,如果他们10个里面有8个都停留在一个方法,那这个方法就很值得怀疑,是需要重点检查的,那到底dnt3.0情况如何,我们一步一步来看看。

1)首先抓包,在cmd下 通过adplus抓包,间隔10秒连抓3个(windbg命令的用法,不是本文的重点,google可以有一箩筐);

 

2)用windbg分别打开3个dump文件,运行!runaway命令,找到耗时的线程;

 3个文件的截图如下:



第1张

 

第2张



第3张

 

 

3)找到10个最耗时的线程:11,12,21,22,34,38,40,46,47,48 。

 

4)通过!clrstack命令输出他们都在干什么(因为太多,这里只选几个代表性的) 

线程 11 :

---------------- 

 0:000> ~11s

eax=02d25354 ebx=001410a8 ecx=02d253ec edx=02d2541c esi=00000360 edi=00000000
eip=7c95847c esp=020bf15c ebp=020bf1cc iopl=0         nv up ei pl zr na pe nc
cs=001b  ss=0023  ds=0023  es=0023  fs=003b  gs=0000             efl=00000246
ntdll!KiFastSystemCallRet:
7c95847c c3              ret
0:011> !clrstack
OS Thread Id: 0x143c (11)
ESP       EIP     
020bf2cc 7c95847c [GCFrame: 020bf2cc] 
020bf2e8 7c95847c [HijackFrame: 020bf2e8] 
020bf32c 69c7bc58 System.Xml.XmlElement.get_XPLocalName()
020bf330 69c6ea0d System.Xml.DocumentXPathNavigator.get_LocalName()
020bf334 69ca30c5 MS.Internal.Xml.XPath.XPathAxisIterator.get_Matches()
020bf344 69ca37a8 MS.Internal.Xml.XPath.XPathChildIterator.MoveNext()
020bf34c 69c99ce1 MS.Internal.Xml.XPath.ChildrenQuery.Advance()
020bf35c 69ca6fcc MS.Internal.Xml.XPath.XPathSelectionIterator.MoveNext()
020bf368 69c83b07 System.Xml.XPathNodeList.ReadUntil(Int32)
020bf378 69c83ac1 System.Xml.XPathNodeList.get_Count()
020bf380 69c72736 System.Xml.XmlNode.SelectSingleNode(System.String)
020bf390 1b9ebe3f Discuz.Cache.DNTCache.RetrieveOriginObject(System.String)
020bf398 1b9ebde3 Discuz.Cache.DNTCache.RetrieveObject(System.String)
020bf3c4 1b9ee906 Discuz.Forum.UserGroups.GetUserGroupList()
020bf3d0 1b9ee853 Discuz.Forum.UserGroups.GetUserGroupInfo(Int32)
020bf3e0 1b9ea7dc Discuz.Forum.PageBase..ctor()
020bf484 1b9e9e60 Discuz.Web.UI.Avatar..ctor()
020bf490 1b9e9deb ASP.tools_avatar_aspx..ctor()
020bf498 1b9e9dc5 __ASP.FastObjectFactory_app_web_idnpx4j6.Create_ASP_tools_avatar_aspx()

。。。。。。这里省略若干字。

---------------------------

 

线程 12 :

----------

0:012> !clrstack
OS Thread Id: 0x10d0 (12)
ESP       EIP     
0213f3b4 7c95847c [HijackFrame: 0213f3b4] 
0213f3f4 69c6f8be System.Xml.DocumentXPathNavigator.MoveToNext()
0213f404 69ca37c8 MS.Internal.Xml.XPath.XPathChildIterator.MoveNext()
0213f40c 69c99ce1 MS.Internal.Xml.XPath.ChildrenQuery.Advance()
0213f41c 69ca6fcc MS.Internal.Xml.XPath.XPathSelectionIterator.MoveNext()
0213f428 69c83b07 System.Xml.XPathNodeList.ReadUntil(Int32)
0213f438 69c83ac1 System.Xml.XPathNodeList.get_Count()
0213f440 69c72736 System.Xml.XmlNode.SelectSingleNode(System.String)
0213f450 1b9ebe3f Discuz.Cache.DNTCache.RetrieveOriginObject(System.String)
0213f458 1b9ebde3 Discuz.Cache.DNTCache.RetrieveObject(System.String)
0213f484 1c8d2242 Discuz.Forum.Templates.GetValidTemplateList()
0213f4b8 1c8d2020 Discuz.Forum.Templates.GetTemplateItem(Int32)
0213f4d4 1b9ea9c2 Discuz.Forum.PageBase..ctor()
0213f578 1b9efa4a Discuz.Web.UI.TopicPage..ctor()
0213f584 1b9ef8ca Discuz.Web.showtopic..ctor()
0213f590 1b9ef7e3 ASP.aspx_2_showtopic_aspx..ctor()
0213f598 1b9ef7bd __ASP.FastObjectFactory_app_web_ompjorep.Create_ASP_aspx_2_showtopic_aspx()

。。。。。。这里省略若干字。

--------------------------- 

线程 22:

--------

0:022> !clrstack
OS Thread Id: 0x11e8 (22)
ESP       EIP     
1c27f1f0 7c95847c [HijackFrame: 1c27f1f0] 
1c27f230 69ca37c8 MS.Internal.Xml.XPath.XPathChildIterator.MoveNext()
1c27f238 69c99ce1 MS.Internal.Xml.XPath.ChildrenQuery.Advance()
1c27f248 69ca6fcc MS.Internal.Xml.XPath.XPathSelectionIterator.MoveNext()
1c27f254 69c83b07 System.Xml.XPathNodeList.ReadUntil(Int32)
1c27f264 69c83ac1 System.Xml.XPathNodeList.get_Count()
1c27f26c 69c72736 System.Xml.XmlNode.SelectSingleNode(System.String)
1c27f27c 1b9ebe3f Discuz.Cache.DNTCache.RetrieveOriginObject(System.String)
1c27f284 1b9ebde3 Discuz.Cache.DNTCache.RetrieveObject(System.String)
1c27f2b0 1c8d2c7b Discuz.Forum.OnlineUsers.GetOnlineAllUserCount()
1c27f2c4 1c8d1683 Discuz.Forum.OnlineUsers.GetCacheOnlineAllUserCount()
1c27f2d4 1b9ea930 Discuz.Forum.PageBase..ctor()
1c27f378 1b9efa4a Discuz.Web.UI.TopicPage..ctor()
1c27f384 1b9ef8ca Discuz.Web.showtopic..ctor()
1c27f390 1b9ef7e3 ASP.aspx_2_showtopic_aspx..ctor()
1c27f398 1b9ef7bd __ASP.FastObjectFactory_app_web_ompjorep.Create_ASP_aspx_2_showtopic_aspx()
。。。。。。这两省略若干字。

---------------------------

 

线程 48:

-------- 

0:048> !clrstack
OS Thread Id: 0x1620 (48)
ESP       EIP     
1f11eb54 7c95847c [HelperMethodFrame: 1f11eb54] 
1f11ebac 79357d11 System.String.ConcatArray(System.String[], Int32)
1f11ebc0 79371853 System.String.Concat(System.String[])
1f11ebd8 7a4aec86 System.Text.RegularExpressions.Regex..ctor(System.String, System.Text.RegularExpressions.RegexOptions, Boolean)
1f11ec0c 7a4bf93a System.Text.RegularExpressions.Regex.Split(System.String, System.String, System.Text.RegularExpressions.RegexOptions)
1f11ec20 1b9ed247 Discuz.Common.Utils.SplitString(System.String, System.String)
1f11ec2c 1c8d1952 Discuz.Common.Utils.InIPArray(System.String, System.String[])
1f11ec48 1c8d1869 Discuz.Forum.PageBase.ValidateUserPermission()
1f11ec54 1b9ea950 Discuz.Forum.PageBase..ctor()
1f11ecf8 1b9efa4a Discuz.Web.UI.TopicPage..ctor()
1f11ed04 1b9ef8ca Discuz.Web.showtopic..ctor()
1f11ed10 1b9ef7e3 ASP.aspx_2_showtopic_aspx..ctor()
1f11ed18 1b9ef7bd __ASP.FastObjectFactory_app_web_ompjorep.Create_ASP_aspx_2_showtopic_aspx()
。。。。。。这里省略若干字。

--------------------------- 


看上面几个线程,虽然代码不完全相同,但是有几个地方是一样的,标红的地方就是, 上面标红的几个线程都执行到了 

Discuz.Cache.DNTCache.RetrieveObject 这里面来,类似这样的代码,这10个线程里面有6个,不再一一列出,有2个没有跟踪到执行动作,另外2个线程在做其他事情(上面的48就是其中一个),我们现在只重点来关注这6个线程,因为她们都同时执行到了 
Discuz.Cache.DNTCache.RetrieveObject ,我认为这里是存在性能问题的,来看看他的源码是怎么样的。

 

 1         /// <summary>
 2         /// 返回指定XML路径下的数据项
 3         /// </summary>
 4         /// <param name="xpath">分级对象的路径</param>
 5         /// <returns></returns>
 6         public virtual object RetrieveOriginObject(string xpath)
 7         {
 8             if (applyMemCached)
 9             {
10                 //向缓存加入新的对象
11                 return cs.RetrieveObject(xpath);
12             }
13             else
14             {
15                 XmlNode node = objectXmlMap.SelectSingleNode(PrepareXpath(xpath));
16                 if (node != null)
17                 {
18                     string objectId = node.Attributes["objectId"].Value;
19 
20                     return cs.RetrieveObject(objectId);
21                 }
22                 return null;
23             }

24         } 

 

再看看 

objectXmlMap.SelectSingleNode 这里是怎么实现的, 请出 reflector

 

public XmlNode SelectSingleNode(string xpath)
{
    XmlNodeList list 
= this.SelectNodes(xpath);
if ((list != null&& (list.Count > 0))
    {
        
return list[0];
    }
    
return null;
}

  

再看 

this.SelectNodes(xpath);

 

public XmlNodeList SelectNodes(string xpath)
{
    XPathNavigator navigator 
= this.CreateNavigator();
if (navigator == null)
    {
        
return null;
    }
    
return new XPathNodeList(navigator.Select(xpath));
}

 

  

挺复杂的,再看 

CreateNavigator

 

public virtual XPathNavigator CreateNavigator()
{
    XmlDocument document 
= this as XmlDocument;
    
if (document != null)
    {
        
return document.CreateNavigator(this);
    }
    
return this.OwnerDocument.CreateNavigator(this);
}



 

哦,到这里看明白了, SelectSingleNode 经过了如下流程最终得到了结果: 

创建 XmlDocument ——》 创建 XPathNavigator ——》 获取所有的节点Nodes ——》 返回第一个节点 

这样一个流程下来会耗多少性能呢,这个每个人的测试方式不一样,所以结果可能也会不一样,感兴趣的童鞋可以自己测试一下,因为我不熟悉这种缓存方案,无法预知他的性能,我只好改成自己熟悉的,最后用的系统自带的HttpRuntime.Cache来做缓存。 

 

改了缓存策略的效果还不错,cpu从之前的 >80% 降到了 <30%,抓个图:

 

 

 

 

 

 

作者: 鸽子飞扬 发表于 2011-06-18 18:41 原文链接

推荐.NET配套的通用数据层ORM框架:CYQ.Data 通用数据层框架
新浪微博粉丝精灵,刷粉丝、刷评论、刷转发、企业商家微博营销必备工具"