[syslog-ng] syslog-ng takes 100% CPU(RHEL 5)

Yoshinobu Ushida y-ushida at nttpc.co.jp
Tue Mar 3 07:25:47 CET 2009


Dear Bazsi-san,

Thank you for the reply.

> The link for the libdbi package that you need is:
Thank you very much. 

> But the real question is whether your experienced 100% CPU usage is the
> same as the fix in 2.1.3. Can you post an strace of the running process
> as it is eating your CPU?
I post result of an strace.(following) Actually a lot of results were
output. I post a part. But I don't understand whether the result of
strace is helpful. Please teach if there is an option of strace that I
should use. 

Best Regards,
Yoshinobu

[root at abc-dbelog tmp]# date
Tue Mar  3 14:37:15 JST 2009
[root at abc-dbelog tmp]# top
top - 14:38:54 up 43 min,  2 users,  load average: 1.47, 1.69, 1.19
Tasks: 116 total,   3 running, 112 sleeping,   0 stopped,   1 zombie
Cpu(s): 70.3%us, 28.7%sy,  0.0%ni,  0.0%id,  0.0%wa,  0.0%hi,  1.0%si,  0.0%st
Mem:   1002888k total,   649304k used,   353584k free,    33820k buffers
Swap:  2031608k total,        0k used,  2031608k free,   417064k cached

  PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM    TIME+  COMMAND                                                                                                               
 3243 nobody    25   0  2860 1028  780 R 96.3  0.1  13:44.22 syslog-ng                                                                                                             
 2444 root      15   0 63644  21m 8208 S  2.0  2.2   1:37.41 Xorg                                                                                                                  
 3288 root      16   0  2164 1096  828 S  1.0  0.1   0:04.91 top                                                                                                                   
    1 root      15   0  2036  680  580 S  0.0  0.1   0:00.37 init      

#the rest is omitted.


[root at abc-dbelog tmp]# strace -p 3243
Process 3243 attached - interrupt to quit
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 39213}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 39728}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 40197}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 41142}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 41623}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 42082}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 43016}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 43485}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 43948}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 47301}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 47879}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 48362}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 49305}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 49755}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 50208}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 51143}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 51627}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 52090}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 53030}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 53510}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 53978}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 54909}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 77672}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 78431}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 126560}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 127445}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 128096}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 129853}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 130491}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 131094}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 132336}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 132916}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 133524}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 134748}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 135348}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 135815}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 139123}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 139621}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 140099}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 141042}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 141529}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 142001}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 142946}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 143732}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 144220}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 145174}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 145675}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 146132}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 169567}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 170223}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 194044}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 268568}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 269240}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 269882}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 271113}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 271764}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 272372}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 273582}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 274220}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 274825}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 276032}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 276533}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 278996}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 279994}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 280481}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 280951}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 281882}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 282337}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 282800}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 283733}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 284402}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 284936}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 285965}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 286454}, NULL) = 0
poll([{fd=5, events=POLLIN}, {fd=6, events=POLLIN}, {fd=4, events=POLLIN}, {fd=7, events=POLLIN}], 4, 0) = 0
gettimeofday({1236058805, 286928}, NULL) = 0
time(NULL)                              = 1236058805
open("/var/log/messages", O_WRONLY|O_CREAT|O_NOCTTY|O_NONBLOCK|O_LARGEFILE, 0644) = -1 EACCES (Permission denied)
time(NULL)                              = 1236058805
gettimeofday({1236058805, 310466}, NULL) = 0
time(NULL)                              = 1236058805
gettimeofday({1236058805, 311108}, NULL) = 0

#the rest is omitted.

-- 
Yoshinobu Ushida <y-ushida at nttpc.co.jp>



More information about the syslog-ng mailing list