当前页面位置: » 丰搜网 » 文档中心 » 详细内容
分析dns日志
在dns服务器运行时，每隔一小时会生成一组如下所示的log_info级日志信息，反馈dns服务器的运行状态：
dec 26 10:23:52 www named[1033]: cleaned cache of 26 rrset
dec 26 10:23:52 www named[1033]: usage 977797432 976760631 cpu=6.55u/6.24s child cpu=0u/0s
dec 26 10:23:52 www named[1033]: nstats 977797432 976760631 0=2 a=13192
cname=321 ptr=11204 mx=1173 txt=4 aaaa=32 any=4956
dec 26 10:23:52 www named[1033]: xstats 977797432 976760631 rr=7629 rnxd=1368
rfwdr=4836 rdupr=51 rfail=159 rferr=0 rerr=12 raxfr=0 rlame=175 ropts=0
ssysq=2082 sans=26234 sfwdq=4520 sdupq=1263 serr=0 rq=30889 riq=4 rfwdq=0
rdupq=259 rtcp=2 sfwdr=4836 sfail=6 sferr=0 snaans=21753 snxd=10276
下面我们就逐句解读一下：
1. dec 26 10:23:52 www named[1033]: cleaned cache of 26 rrset
这是每一组日志信息的第一行，表示正在清空cache。
其中：
dec 26 10:23:52 表示日志生成时间
www 显示dns服务器所在机器名
named[1033]： 显示dns服务器进程名与进程id
cleaned cache of 26 rrset 表示正在清除cache
2. dec 26 10:23:52 www named[1033]: usage 977797432 976760631 cpu=6.55u
/6.24s child cpu=0u/0s
这一行是usage行，用于统计dns服务器占用的cpu时间。
其中：
dec 26 10:23:52 表示日志生成时间
www 显示dns服务器所在机器名
named[1033]： 显示dns服务器进程名与进程id
usage 行标记
977797432 976760631 977797432-976760631的值就是dns服务器运行的总秒数
cpu=6.55u/6.24s 代表dns服务器使用了用户态6.55秒，系统态6.24秒（u代表user，
s代表system），
child cpu 代表dns服务器子进程的cpu占用情况。
3. dec 26 10:23:52 www named[1033]: nstats 977797432 976760631 0=2 a=13192
cname=321 ptr=11204 mx=1173 txt=4 aaaa=32 any=4956
这一行是nstats行，用于统计接收到的查询总数
其中：
dec 26 10:23:52 表示日志生成时间
www 显示dns服务器所在机器名
named[1033]： 显示dns服务器进程名与进程id
nstats 行标记
977797432 976760631 977797432-976760631的值就是dns服务器运行的总秒数
0=2 代表未知类型的dns查询2个
a=13192 代表a类地址查询13192个（最标准）
cname=321 代表cname类地址查询321个（一般是有些版本的sendmail使用cname程序
规范化邮件地址而发出的，还有就是dig或nslookup发出的）
ptr=11204 代表指针查询11204个（许多软件通过这种方法来查找ip地址）
mx=1173 代表邮件交换器的查询1173个（是由邮件发送程序发起的）
txt=4 代表应用程序进行的文本查询共有4个
aaaa=32 代表aaaa类查询32个
any=4956 有些sendmail使用的地址查询方式，共4956个
注：还有可能有：
ns=xx 代表名字服务器查询（例如：名字服务器试图查找根域的服务器）
soa=xx 代表辅助dns更新
hinfo=xx 主机信息查询
nsap=xx 将域名映射成osi网络服务访问点地址
axfr=xx 辅助dns的区传送
这些在本例中并未出现。
4. dec 26 10:23:52 www named[1033]: xstats 977797432 976760631 rr=7629 rnxd=1368
rfwdr=4836 rdupr=51 rfail=159 rferr=0 rerr=12 raxfr=0 rlame=175 ropts=0 ssysq=2082
sans=26234 sfwdq=4520 sdupq=1263 serr=0 rq=30889 riq=4 rfwdq=0
rdupq=259 rtcp=2
sfwdr=4836 sfail=6 sferr=0 snaans=21753 snxd=10276
这是xstats行，它用于统计其它一些数据。
其中：
dec 26 10:23:52 表示日志生成时间
www 显示dns服务器所在机器名
named[1033]： 显示dns服务器进程名与进程id
nstats 行标记
977797432 976760631 977797432-976760631的值就是dns服务器运行的总秒数
rr=7629 代表收到其它主机的响应共有7629个（dns向其它机器或进程发出的查询得到的响应数、
与rq无关）
rnxd=1368 代表收到“没有这样的域”回答共有1368个
rfwdr=108 收到对原始查询的响应为108个
rdupr=51 重复响应51个（当dns在它悬而未决的查询列表中，找不到引起该响应的原始查询时，
这个响应就是重复响应）
rfail=159 收到servfail（远程服务器错误）159个
rferr=0 没有收到formerr（远程名字服务器认为本地名字服务器的查询有格式错误）
rerr=12 收到除了servfail、formerr以外的错误12个
raxfr=0 共有0次区传送
rlame=175 收到175个坏授权（意味着有的区被授权给其它名字服务器，而这个名字服务器不是这
个区的权威）
ropts=0 共收到带有ip选项的包的个数为0
ssysq=2082 共发出系统查询2082个（系统查询是由本地名字服务器进行的查询。大多数都是针对
根名字服务器的）
sans=26234 共回答了查询26234个
sfwdq=4520 不在这个名字服务器，而转发共4520个
sdupq=1263 重复查询数1263个
serr=0 发出的非servfail、formerr的错误总数
rq=30889 收到的查询共有30889个
riq=4 收到反向查询4个（反向查询是为了将地址映射为名字，现在这个功能被 ptr实现了。较早
的nslookup才使用这种查询）
rfwdq=0 没有需要进一步处理的查询
rdupq=259 重复查询共有259个
rtcp=2 通过tcp连接收到2个查询（一般使用udp）
sfwdr=4836 来自其它名字服务器转发的响应4836个
sfail=6 发出被认为servfail响应共6个
sferr=0 发出的被认为formerr的响应个数
snaans=21753 非权威回答共21753
snxd=10276 发出没有这个域回答10276个
这些统计数据都是从dns开启后到现在的总统计，而非本小时内的统计数字。如何衡量dns服务器的
负载呢？很简单，将总查询数除以dns运行的总时间，不就知道了吗？在本例中：dns服务器已运行
了： 977797432-976760631=1036801秒=288小时
注：从第2、3、4行都可以得到
而总查询请求有： 2+13192+321+11204+1173+4+32+4956=20884次
注：从第2行都可以得到,也就是每小时107次查询请求，每秒不到2次，可见负载还是比较小的。
