Log messages going into imap/pop stream

[Michal Příhoda]

Hi,

I have cyrus 2.1.16, and I'm experiencing strange behaviour of message 
logging. Look at the imtest output:

S: * OK fs Cyrus IMAP4 v2.1.16 server ready
C: C01 CAPABILITY
S: * CAPABILITY IMAP4 IMAP4rev1 ACL QUOTA LITERAL+ MAILBOX-REFERRALS 
NAMESPACE UIDPLUS ID NO_ATOMIC_RENAME UNSELECT CHILDREN MULTIAPPEND SORT 
THREAD=ORDEREDSUBJECT THREAD=REFERENCES IDLE STARTTLS LISTEXT 
LIST-SUBSCRIBED X-NETSCAPE
S: C01 OK Completed
Please enter your password:
C: L01 LOGIN root {0}
S: + go ahead
C: <omitted>
S: <181>Jan 26 12:15:38 imapd[10975]: badlogin: 
us.spinet.cz[193.86.200.20] plaintext root SASL(-13): authentication 
failure: checkpass failed

The important line is the last one - evidently log message felt into 
imap output. The bad thing is, that it happens even after successfull 
login, but of course the IMAP clients can't get it.

I found out, that it never happens on first connection to the process, 
so now my solution is -U 1, which I don't like at all. I can't find the 
cause.

Here is a part of strace output from one of these processes. It's just a 
  few lines, which seem strange to me:

rt_sigaction(SIGPIPE, {0x402babc0, [], SA_RESTORER, 0x4020cec8}, 
{SIG_IGN}, 8) = 0
send(1, "<183>Jan  8 09:41:55 imapd[27819"..., 55, 0) = -1 EBADF (Bad 
file descriptor)
close(1)                                = -1 EBADF (Bad file descriptor)
socket(PF_UNIX, SOCK_STREAM, 0)         = 1
fcntl64(1, F_SETFD, FD_CLOEXEC)         = 0
connect(1, {sa_family=AF_UNIX, path="/dev/log"}, 16) = 0
send(1, "<183>Jan  8 09:41:55 imapd[27819"..., 55, 0) = 55
rt_sigaction(SIGPIPE, {SIG_IGN}, NULL, 8) = 0
dup2(0, 1)                              = 1
dup2(0, 2)                              = 2

Hopefully someone has an idea what's wrong. If you need more info, 
please let me know. And one more thing - this problem isn't new, it 
happened before, but quite rarely. But last few weeks, after upgrading 
to 2.1.16, it's almost all the time.

--
Michal Prihoda