[Sysadmins] syslogd нагружает диск

Караваев Павел pppd на yandex.ru
Ср Окт 17 16:52:45 MSK 2012


Приветствую.

Установил informika-6.0.0-school-server-x86_64-ru-install-dvd5.iso на свежий серв и наблюдаю следующую картину. СтОит только добавить в /etc/syslog.conf такие строки:

mail.*                                                         /var/log/maillog_vir
*.*                                                            /var/log/swatch.log
*.*                                                             @molot.glazov.net

как atop сразу показывает высокую нагрузку DSK sda и sdb. 

ATOP - pr                                            2012/10/17  16:21:23                                            ------                                              10s elapsed
PRC | sys    0.15s  | user   0.15s  |               | #proc    189  | #trun      1  | #tslpi   565 |  #tslpu     2 |  #zombie    0 |  clones    40 |               |  #exit     29 |
CPU | sys       2%  | user      2%  | irq       1%  |               | idle    107%  | wait     88% |               |  steal     0% |  guest     0% |  curf 2.80GHz |  curscal   ?% |
cpu | sys       1%  | user      2%  | irq       1%  |               | idle     23%  | cpu000 w 73% |               |  steal     0% |  guest     0% |  curf 2.80GHz |  curscal   ?% |
cpu | sys       1%  | user      1%  | irq       0%  |               | idle     83%  | cpu001 w 15% |               |  steal     0% |  guest     0% |  curf 2.80GHz |  curscal   ?% |
CPL | avg1    1.36  | avg5    0.83  |               | avg15   0.71  |               |              |  csw    12289 |  intr   11176 |               |               |  numcpu     2 |
MEM | tot     3.6G  | free  846.4M  | cache   1.9G  | dirty   0.2M  | buff   48.1M  |              |  slab  192.3M |               |               |               |               |
SWP | tot     4.0G  | free    4.0G  |               |               |               |              |               |               |               |  vmcom   3.8G |  vmlim   5.8G |
MDD |          md2  | busy      0%  | read      36  | write   1509  | KiB/r      4  |              |  KiB/w      4 |  MBr/s   0.01 |  MBw/s   0.59 |  avq     0.00 |  avio 0.00 ms |
DSK |          sda  | busy     83%  | read       5  | write    748  | KiB/r      7  |              |  KiB/w      8 |  MBr/s   0.00 |  MBw/s   0.59 |  avq     1.06 |  avio 11.0 ms |
DSK |          sdb  | busy     79%  | read      27  | write    748  | KiB/r      4  |              |  KiB/w      8 |  MBr/s   0.01 |  MBw/s   0.59 |  avq     1.59 |  avio 10.2 ms |
NET | transport     | tcpi     370  | tcpo     549  | udpi    1359  | udpo    1489  | tcpao     11 |  tcppo     13 |  tcprs      3 |  tcpie      0 |  tcpor      6 |  udpip      0 |
NET | network       | ipi     1805  | ipo     2046  | ipfrw      0  | deliv   1803  |              |               |               |               |  icmpi     45 |  icmpo      4 |
NET | eth0      0%  | pcki    1666  | pcko    1823  | si  145 Kbps  | so  580 Kbps  | coll       0 |  mlti       0 |  erri       0 |  erro       0 |  drpi       0 |  drpo       0 |
NET | lo      ----  | pcki     230  | pcko     230  | si   60 Kbps  | so   60 Kbps  | coll       0 |  mlti       0 |  erri       0 |  erro       0 |  drpi       0 |  drpo       0 |

Процесс syslogd меняет статус с постоянного "S" на постоянный "D". iotop показывает 

  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN     IO>    COMMAND                                                                                                              
 2334 be/3 root        0.00 B/s    0.00 B/s  0.00 % 96.52 % [jbd2/md2-8]
19416 be/4 syslogd     0.00 B/s  115.62 K/s  0.00 %  1.13 % syslogd -u syslogd -j /var/resolv


Комментирую эти строки, перезапускаю syslogd, смотрю atop - нагрузка не более нескольких процентов, iotop показывает IO от [jbd2/md2-8] нормальные 0 - 5-10%. Пробовал по одной - по две строки из приведенных, нагрузка возрастает примерно пропорционально.

[root на pr etc]# uname -a
Linux pr.______.net 3.0.46-std-def-alt0.M60P.1 #1 SMP Sat Oct 13 16:21:52 UTC 2012 x86_64 GNU/Linux
[root на pr etc]# hdparm -tT /dev/sda

/dev/sda:
 Timing cached reads:   11254 MB in  2.00 seconds = 5630.35 MB/sec
 Timing buffered disk reads: 322 MB in  3.00 seconds = 107.25 MB/sec
[root на pr etc]# hdparm -tT /dev/sdb

/dev/sdb:
 Timing cached reads:   11074 MB in  2.00 seconds = 5539.72 MB/sec
 Timing buffered disk reads: 336 MB in  3.05 seconds = 110.07 MB/sec
[root на pr etc]# hdparm -tT /dev/md2

/dev/md2:
 Timing cached reads:   11042 MB in  2.00 seconds = 5524.75 MB/sec
 Timing buffered disk reads: 328 MB in  3.01 seconds = 108.83 MB/sec
[root на pr etc]# cat /proc/mdstat 
Personalities : [raid1] 
md1 : active raid1 sda1[0]
      4193216 blocks [1/1] [U]
      
md2 : active raid1 sdb2[1] sda2[0]
      308373440 blocks [2/2] [UU]
      
unused devices: <none>
[root на pr etc]# 

Система расположена на md2 (диски разных объемов, но с однаковыми таблицами разделов). Как лечить и какие танцы плясать?


Подробная информация о списке рассылки Sysadmins