[Dovecot] FreeBSD 6.1-RELEASE/kqueue high CPU load

[Paul Lathrop]

LI Xin wrote:
>> My ktrace/kdump output is huge. Here is a typical excerpt:
>>
>>  44146 imap     0.005657 CALL  gettimeofday(0x80d00a0,0x80d00a8)
>>  44146 imap     0.005661 RET   gettimeofday 0
>>  44146 imap     Events dropped.
>>  44146 imap     0.005667 CALL  kevent(0x5,0,0,0xbfbfe890,0x1,0xbfbfe888)
>>  44146 imap     0.005671 RET   kevent 1
>>  44146 imap     0.005674 CALL  gettimeofday(0xbfbfe8f0,0)
>>  44146 imap     0.005677 RET   gettimeofday 0
>>  44146 imap     0.005680 CALL  kevent(0x4,0,0,0x80df000,0x4,0xbfbfe8e8)
>>  44146 imap     0.005683 RET   kevent 1
>>  44146 imap     0.005686 CALL  gettimeofday(0x80d00a0,0x80d00a8)
>>  44146 imap     0.005689 RET   gettimeofday 0
>>  44146 imap     0.005692 CALL  gettimeofday(0x80d00a0,0x80d00a8)
>>  44146 imap     0.005695 RET   gettimeofday 0
>>  44146 imap     0.005697 CALL  kevent(0x5,0,0,0xbfbfe890,0x1,0xbfbfe888)
>>  44146 imap     0.005701 RET   kevent 1
>>  44146 imap     0.005704 CALL  gettimeofday(0xbfbfe8f0,0)
>>  44146 imap     0.005708 RET   gettimeofday 0
>>  44146 imap     0.005710 CALL  kevent(0x4,0,0,0x80df000,0x4,0xbfbfe8e8)
>>  44146 imap     0.005713 RET   kevent 1
>>  44146 imap     0.005716 CALL  gettimeofday(0x80d00a0,0x80d00a8)
>>  44146 imap     0.005720 RET   gettimeofday 0
>>  44146 imap     0.005722 CALL  gettimeofday(0x80d00a0,0x80d00a8)
>>  44146 imap     0.005725 RET   gettimeofday 0
>>  44146 imap     0.005728 CALL  kevent(0x5,0,0,0xbfbfe890,0x1,0xbfbfe888)
>>  44146 imap     Events dropped.
>>  44146 imap     0.005763 RET   kevent 1
>>  44146 imap     Events dropped.
>>  44146 imap     0.005768 RET   gettimeofday 0
>>  44146 imap     0.005771 CALL  kevent(0x4,0,0,0x80df000,0x4,0xbfbfe8e8)
>>  44146 imap     0.005774 RET   kevent 1
>>  44146 imap     0.005777 CALL  gettimeofday(0x80d00a0,0x80d00a8)
>>  44146 imap     0.005780 RET   gettimeofday 0
>>  44146 imap     0.005782 CALL  gettimeofday(0x80d00a0,0x80d00a8)
>>  44146 imap     0.005786 RET   gettimeofday 0
>>  44146 imap     0.005789 CALL  kevent(0x5,0,0,0xbfbfe890,0x1,0xbfbfe888)
>>  44146 imap     0.005793 RET   kevent 1
>>  44146 imap     0.005795 CALL  gettimeofday(0xbfbfe8f0,0)
>>  44146 imap     0.005798 RET   gettimeofday 0
>>  44146 imap     0.005800 CALL  kevent(0x4,0,0,0x80df000,0x4,0xbfbfe8e8)
>>  44146 imap     Events dropped.
>>
>>
>> I haven't been able to get a .core file yet. More info as I catch it...
>>     
>
> Er...  EPERM here is not normal here.  Is there any log indicating some
> problem when this occour?
>
> Cheers,
>   
Sorry, EPERM? Where do you see that? What does it mean.

As for logs, the only errors I see (on a daily basis, though) are:

+pid 42498 (imap), uid 1016: exited on signal 11
+pid 38323 (imap), uid 1016: exited on signal 11
+pid 43138 (imap), uid 1016: exited on signal 11
+pid 43135 (imap), uid 1016: exited on signal 11
+pid 43804 (imap), uid 1016: exited on signal 11
+pid 38340 (imap), uid 1016: exited on signal 11


Last night, I got this:

+Out of ktrace request objects.
+pid 45376 (imap), uid 1016: exited on signal 11
+pid 45403 (imap), uid 1016: exited on signal 11
+pid 45518 (imap), uid 1016: exited on signal 11
+pid 46201 (imap), uid 1016: exited on signal 11
+pid 47169 (imap), uid 1016: exited on signal 11
+pid 47197 (imap), uid 1016: exited on signal 11


And dovecot just died completely. I restarted it this morning.

Hope that helps track this down.

--Paul
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://dovecot.org/pipermail/dovecot/attachments/20060919/3c45694d/attachment.html 
-------------- next part --------------
A non-text attachment was scrubbed...
Name: signature.asc
Type: application/pgp-signature
Size: 254 bytes
Desc: OpenPGP digital signature
Url : http://dovecot.org/pipermail/dovecot/attachments/20060919/3c45694d/attachment.pgp