Chinaunix首页 | 论坛 | 博客
  • 博客访问: 2106353
  • 博文数量: 220
  • 博客积分: 8531
  • 博客等级: 中将
  • 技术积分: 4976
  • 用 户 组: 普通用户
  • 注册时间: 2007-07-18 13:33
文章分类

全部博文(220)

文章存档

2017年(1)

2015年(1)

2014年(5)

2013年(6)

2012年(6)

2011年(30)

2010年(37)

2009年(53)

2008年(41)

2007年(40)

分类: LINUX

2011-01-27 11:48:52

很多时候发现linux系统输入一些命令很慢,用top查看IOwait占用CPU很高,top下面列出的进程中,不论按cpu排序、内存排序、时间排序,都看不出来到底哪个进程(哪个分区)占用ipwait最高。

Waiting

CPU花费在等待I/O操作上的总时间,与blocked相似,一个系统不应该花费太多的时间在等待I/O操作上,否则你应该进一步检测I/O子系统是否存在瓶颈。


那么到底怎么知道是哪个进程导致iowait过高?

系统日志是没有记录这些内容的,但是内核中有相应的方式。Linux 内核里提供了一个 block_dump 参数用来把 block 读写(WRITE/READ)状况 dump 到日志里,这样可以通过 dmesg 命令来查看
看一下介绍

block_dump enables block I/O debugging when set to a nonzero value. If you want to find out which process caused the disk to spin up(see /proc/sys/vm/laptop_mode ), you can gather information by setting the flag.


When this flag is set, Linux reports all disk read and write operations that take place, and all block dirtyings done to files. This makes it possible to debug why a disk needs to spin up, and to increase battery life even more. The output of block_dump is written to the kernel output, and it can be retrieved using "dmesg". When you use block_dump and your kernel logging level also includes kernel debugging messages, you probably want to turn off klogd, otherwise the output of block_dump will be logged, causing disk activity that is not normally there.


我们首先想个办法让iowait上来,可以用dd,也可以用cp,下面一个简单的脚本:

[root@fan3838 tmp]# cat a.sh
#!/bin/bash
while true
do
   cd /usr/share
   mkdir doc1
   cp -ra doc/* doc1/
   rm -rf doc1
done

[root@fan3838 tmp]# ./a.sh &


如果使用block_dump那么需要关闭syslog,否则klog会打印很多日志到messages中,这样更加让系统不堪重负。

service syslog stop


打开block dump:

echo 1 > /proc/sys/vm/block_dump


统计方法:

[root@fan3838 tmp]# dmesg | egrep "READ|WRITE|dirtied" | egrep -o '([a-zA-Z]*)' | sort | uniq -c | sort -rn | head
   1675 kjournald
   1060 cp
      3 bash


网上有人写了一个perl脚本来处理输出,能得到更直观的结果:

参考:http://www.xaprb.com/blog/2009/08/23/how-to-find-per-process-io-statistics-on-linux/

下载地址:
这是一个perl脚本,原理是:将dmesg清空,然后统计1秒内dmesg所dump的block信息。
while true; do sleep 1; dmesg -c; done | perl iodump
因为给出的方法只要结果不要dmesg输出内容,所以封装到while true中然后交给iodump处理
所以执行此命令需要ctrl+c停止之后才能看到结果。

[root@fan3838 tmp]# while true; do sleep 1; dmesg -c; done|perl /root/iodump
# Caught SIGINT.
TASK          PID      TOTAL      READ     WRITE     DIRTY DEVICES
kjournald     349       4185         0      4185         0 sda2
cp            4701      1051      1051         0         0 sda2
bash          4762         1         1         0         0 sda2

上面这个结果已经很清楚了,最高的就是cp产生的,说明cp在大概1秒钟之内读写最多,达到1051次
那么造成iowait的罪魁祸首就是cp了。
当然应该多执行几次能得到更精确的结果,有可能是“协助作案”呢。

另:为什么kjournald最多而不“验证”一下这个进程呢?这进程是干什么的?搜索一下,ext3文件系统日志相关。这个进程正常。

测试完毕不要忘记关掉block_dump和启动syslog:
echo 0 > /proc/sys/vm/block_dump
service syslog start

阅读(8781) | 评论(0) | 转发(5) |
给主人留下些什么吧!~~