Weird behavior between Mulberry and Cyrus 2.1.12

[Sebastian Hagedorn]

Hi,

I've got a sporadic problem with Mulberry and Cyrus. What happens is that 
somehow the connection is dropped, but I'm not sure which side is doing the 
dropping. Usually this results in Mulberry crashing. Sometimes it also 
results in an inability to open my INBOX. In Mulberry the mailbox icon will 
briefly change to green (to indicate it's open), but change back to red 
almost immediately.

I've collected logging info both on the client and the server. First, the 
client:

--> #5 Starting IMAP Session on Fri Oct 31 15:42:25 2003: imap.uni-koeln.de

--> #5.-1610571284 Fri Oct 31 15:42:25 2003
* OK cyrus.rrz.uni-koeln.de Cyrus IMAP4 v2.1.12-Invoca-RPM-2.1.12-3 server 
ready

--> #5.-1610571284 Fri Oct 31 15:42:25 2003
A00001 CAPABILITY
* CAPABILITY IMAP4 IMAP4rev1 ACL QUOTA LITERAL+ MAILBOX-REFERRALS NAMESPACE 
UIDPLUS ID NO_ATOMIC_RENAME UNSELECT CHILDREN MULTIAPPEND SORT 
THREAD=ORDEREDSUBJECT THREAD=REFERENCES IDLE STARTTLS LOGINDISABLED 
AUTH=NTLM AUTH=DIGEST-MD5 AUTH=CRAM-MD5 LISTEXT LIST-SUBSCRIBED 
ANNOTATEMORE X-NETSCAPE
A00001 OK Completed

--> #5.-1610571284 Fri Oct 31 15:42:25 2003
A00002 STARTTLS
A00002 OK Begin TLS negotiation now

--> #5.-1610571284 Fri Oct 31 15:42:25 2003
A00003 CAPABILITY
* CAPABILITY IMAP4 IMAP4rev1 ACL QUOTA LITERAL+ MAILBOX-REFERRALS NAMESPACE 
UIDPLUS ID NO_ATOMIC_RENAME UNSELECT CHILDREN MULTIAPPEND SORT 
THREAD=ORDEREDSUBJECT THREAD=REFERENCES IDLE STARTTLS AUTH=PLAIN AUTH=NTLM 
AUTH=DIGEST-MD5 AUTH=CRAM-MD5 LISTEXT LIST-SUBSCRIBED ANNOTATEMORE 
X-NETSCAPE
A00003 OK Completed

--> #5.-1610571284 Fri Oct 31 15:42:26 2003
A00004 SELECT INBOX
* FLAGS (\Answered \Flagged \Draft \Deleted \Seen NotJunk Junk JunkRecorded 
NonJunk Cyrusoft.Mulberry.01 $Label2 $Label1 $Label3 $Label4 $Label5 
$MDNSent)
* OK [PERMANENTFLAGS (\Answered \Flagged \Draft \Deleted \Seen NotJunk Junk 
JunkRecorded NonJunk Cyrusoft.Mulberry.01 $Label2 $Label1 $Label3 $Label4 
$Label5 $MDNSent \*)]
* 46 EXISTS
* 0 RECENT
* OK [UIDVALIDITY 1039509378]
* OK [UIDNEXT 58164]
A00004 OK [READ-WRITE] Completed

--> #5.-1610571284 Fri Oct 31 15:42:26 2003
A00005 SEARCH UNSEEN
* SEARCH
A00005 OK Completed (0 msgs in 0.000 secs)

--> #5.-1610571284 Fri Oct 31 15:42:26 2003
A00006 SEARCH DELETED
* SEARCH
A00006 OK Completed (0 msgs in 0.000 secs)

--> #5.-1610571284 Fri Oct 31 15:42:26 2003
A00007 MYRIGHTS INBOX
* MYRIGHTS INBOX lrswipcda0
A00007 OK Completed

--> #5.-1610571284 Fri Oct 31 15:42:26 2003
A00008 THREAD REFERENCES us-ascii ALL

What's going on here?? Instead of a reply to the THREAD command the server 
opens a new session? That's what it looks like ...

--> #5.-1610571284 Fri Oct 31 15:42:26 2003
* OK cyrus.rrz.uni-koeln.de Cyrus IMAP4 v2.1.12-Invoca-RPM-2.1.12-3 server 
ready

--> #5.-1610571284 Fri Oct 31 15:42:26 2003
A00001 CAPABILITY
* CAPABILITY IMAP4 IMAP4rev1 ACL QUOTA LITERAL+ MAILBOX-REFERRALS NAMESPACE 
UIDPLUS ID NO_ATOMIC_RENAME UNSELECT CHILDREN MULTIAPPEND SORT 
THREAD=ORDEREDSUBJECT THREAD=REFERENCES IDLE STARTTLS LOGINDISABLED 
AUTH=NTLM AUTH=DIGEST-MD5 AUTH=CRAM-MD5 LISTEXT LIST-SUBSCRIBED 
ANNOTATEMORE X-NETSCAPE
A00001 OK Completed

--> #5.-1610571284 Fri Oct 31 15:42:26 2003
A00002 STARTTLS
A00002 OK Begin TLS negotiation now

--> #5.-1610571284 Fri Oct 31 15:42:26 2003
A00003 CAPABILITY
* CAPABILITY IMAP4 IMAP4rev1 ACL QUOTA LITERAL+ MAILBOX-REFERRALS NAMESPACE 
UIDPLUS ID NO_ATOMIC_RENAME UNSELECT CHILDREN MULTIAPPEND SORT 
THREAD=ORDEREDSUBJECT THREAD=REFERENCES IDLE STARTTLS AUTH=PLAIN AUTH=NTLM 
AUTH=DIGEST-MD5 AUTH=CRAM-MD5 LISTEXT LIST-SUBSCRIBED ANNOTATEMORE 
X-NETSCAPE
A00003 OK Completed

--> #5.-1610571284 Fri Oct 31 15:42:26 2003
A00004 SELECT INBOX
* FLAGS (\Answered \Flagged \Draft \Deleted \Seen NotJunk Junk JunkRecorded 
NonJunk Cyrusoft.Mulberry.01 $Label2 $Label1 $Label3 $Label4 $Label5 
$MDNSent)
* OK [PERMANENTFLAGS (\Answered \Flagged \Draft \Deleted \Seen NotJunk Junk 
JunkRecorded NonJunk Cyrusoft.Mulberry.01 $Label2 $Label1 $Label3 $Label4 
$Label5 $MDNSent \*)]
* 46 EXISTS
* 0 RECENT
* OK [UIDVALIDITY 1039509378]
* OK [UIDNEXT 58164]
A00004 OK [READ-WRITE] Completed

--> #5.-1610571284 Fri Oct 31 15:42:26 2003
A00005 SEARCH UNSEEN
* SEARCH
A00005 OK Completed (0 msgs in 0.000 secs)

--> #5.-1610571284 Fri Oct 31 15:42:26 2003
A00006 SEARCH DELETED
* SEARCH
A00006 OK Completed (0 msgs in 0.000 secs)

--> #5.-1610571284 Fri Oct 31 15:42:26 2003
A00007 UNSELECT
A00007 OK Completed

--> #2.-1610571284 Fri Oct 31 15:42:45 2003
A00005 LOGOUT
* BYE LOGOUT received
A00005 OK Completed

--> #5.-1610571284 Fri Oct 31 15:42:45 2003
A00008 LOGOUT
* BYE LOGOUT received
A00008 OK Completed

--> #5 Stopping IMAP Session on Fri Oct 31 15:42:45 2003


So much for the client. On the server I've got this:

[root at lvr1 a0620]# cat 26740
---------- a0620 Fri Oct 31 15:42:26 2003

<1067611346<A00004 SELECT INBOX
>1067611346>* FLAGS (\Answered \Flagged \Draft \Deleted \Seen NotJunk Junk 
JunkRecorded NonJunk Cyrusoft.Mulberry.01 $Label2 $Label1 $Label3 $Label4 
$Label5 $MDNSent)
* OK [PERMANENTFLAGS (\Answered \Flagged \Draft \Deleted \Seen NotJunk Junk 
JunkRecorded NonJunk Cyrusoft.Mulberry.01 $Label2 $Label1 $Label3 $Label4 
$Label5 $MDNSent \*)]
* 46 EXISTS
* 0 RECENT
* OK [UIDVALIDITY 1039509378]
* OK [UIDNEXT 58164]
A00004 OK [READ-WRITE] Completed
<1067611346<A00005 SEARCH UNSEEN
>1067611346>* SEARCH
A00005 OK Completed (0 msgs in 0.000 secs)
<1067611346<A00006 SEARCH DELETED
>1067611346>* SEARCH
A00006 OK Completed (0 msgs in 0.000 secs)
<1067611346<A00007 MYRIGHTS INBOX
>1067611346>* MYRIGHTS INBOX lrswipcda0
A00007 OK Completed
<1067611346<A00008 THREAD REFERENCES us-ascii ALL
[root at lvr1 a0620]#

Any ideas? Unfortunately I can't reproduce this, it just happens every once 
in a while.

Greetings, Sebastian Hagedorn
--
Sebastian Hagedorn M.A. - RZKR-R1 (Gebäude 52), Zimmer 18
Zentrum für angewandte Informatik - Universitätsweiter Service RRZK
Universität zu Köln / Cologne University - Tel. +49-221-478-5587