381759 - "Delete attachment" very slow, downloads entire message, disappears message, Dovecot IMAP

Status: RESOLVED WORKSFORME

(MailNews Core :: Networking: IMAP, defect)

Description

[Bryce Mozilla Nesbitt]

User-Agent:       Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.8.1.2) Gecko/20061107 SUSE/1.1.1-0.1 SeaMonkey/1.1.1
Build Identifier: Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.8.1.2) Gecko/20061107 SUSE/1.1.1-0.1 SeaMonkey/1.1.1

I don't think that "delete attachment" works correctly in Seamonkey, at least with the Dovecot http://www.dovecot.org/ IMAP server.

Reproducible: Always

Steps to Reproduce:

1. Receive a message with a large attachment.
2. Select attachment, select "delete" or "delete all".
3. Observe that the entire attachment is downloaded first (this is undesirable and often very slow).
4. Observe that the message disappears from the inbox.  This is disturbing, because you think it's gone.

If you exit seamonkey and restart, the message will be back but with the attachment still attached.

Comment 1

[Bryce Mozilla Nesbitt]

(To be clear: when selecting delete it appears that the message is downloaded three times from the IMAP server:

Once initially to view it
Once to delete the attachment
Then again to reload the supposedly smaller message from the server.

For large attachments, this is undesirable, it would be much more usable if it used the local copy of the message).

Comment 2

[Bryce Mozilla Nesbitt]

bryce@hardhat:~> echo $NSPR_LOG_MODULES
protocol:5
bryce@hardhat:~> echo $NSPR_LOG_FILE
/tmp/filename
bryce@hardhat:~> seamonkey

Produces an empty /tmp/filename, despite
http://www.mozilla.org/quality/mailnews/mail-troubleshoot.html#imap

Comment 3

[David :Bienvenu]

NSPR_LOG_MODULES should be IMAP:5

Comment 4

[Bryce Mozilla Nesbitt]

Delete an attachment.  After delete the message disappeared from the mailbox briefly, then came back with attachment gone (no bug):

-1222923584[8082548]: considering playing queued url:imap://[e-mail]:143/addmsgflags>UID>/Testing>5>9
-1222923584[8082548]: creating protocol instance to play queued url:imap://[e-mail]:143/addmsgflags>UID>/Testing>5>9
-1222923584[8082548]: failed creating protocol instance to play queued url:imap://[e-mail]:143/addmsgflags>UID>/Testing>5>9
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:SendData: DONE
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=23 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: 47 OK Idle completed.
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:ProcessCurrentURL: entering
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:ProcessCurrentURL:imap://[e-mail]:143/appendmsgfromfile%3E/Testing:  = currentUrl
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:SendData: 48 append "BasiliX/Testing" (\Seen) {2970+}
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:SendData: Return-Path: <[e-mail]>
X-Original-To: [e-mail]
Delivered-To: [e-mail]
Received: from mx13.futurequest.net (mx13.futurequest.net [69.5.6.186])
        by idiom.com (Postfix) with SMTP id 59D8D125A24
        for <[e-mail]>; Wed, 23 May 2007 21:16:44 -0700 (PDT)
Received: (qmail 23606 invoked from network); 24 May 2007 04:16:43 -0000
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:SendData: 
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=12 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: * 3 EXISTS
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=25 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: 48 OK Append completed.
-1222923584[8082548]: queuing url:imap://[e-mail]:143/select>/Testing
-1222923584[8082548]: considering playing queued url:imap://[e-mail]:143/addmsgflags>UID>/Testing>5>9
-1222923584[8082548]: creating protocol instance to play queued url:imap://[e-mail]:143/addmsgflags>UID>/Testing>5>9
-1222923584[8082548]: failed creating protocol instance to play queued url:imap://[e-mail]:143/addmsgflags>UID>/Testing>5>9
-1222923584[8082548]: considering playing queued url:imap://[e-mail]:143/addmsgflags>UID>/Testing>5>9
-1222923584[8082548]: creating protocol instance to play queued url:imap://[e-mail]:143/addmsgflags>UID>/Testing>5>9
-1222923584[8082548]: playing queued url:imap://[e-mail]:143/addmsgflags>UID>/Testing>5>9
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:ProcessCurrentURL: entering
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:ProcessCurrentURL:imap://[e-mail]:143/addmsgflags%3EUID%3E/Testing%3E5%3E9:  = currentUrl
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:SendData: 49 uid store 5 +Flags (\Seen \Deleted)
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=42 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: * 2 FETCH (UID 5 FLAGS (\Deleted \Seen))
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=24 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: 49 OK Store completed.
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:SendData: 50 check
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=24 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: 50 OK Check completed.
-1222923584[8082548]: considering playing queued url:imap://[e-mail]:143/select>/Testing
-1222923584[8082548]: creating protocol instance to play queued url:imap://[e-mail]:143/select>/Testing
-1222923584[8082548]: playing queued url:imap://[e-mail]:143/select>/Testing
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:ProcessCurrentURL: entering
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:ProcessCurrentURL:imap://[e-mail]:143/select%3E/Testing:  = currentUrl
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:SendData: 51 noop
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=23 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: 51 OK NOOP completed.
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:SendData: 52 UID fetch 6:* (FLAGS)
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=33 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: * 3 FETCH (FLAGS (\Seen) UID 6)
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=24 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: 52 OK Fetch completed.
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:SendData: 53 UID fetch 6 (UID RFC822.SIZE FLAGS BODY.PEEK[HEADER.FIELDS (From To Cc Subject Date Message-ID Priority X-Priority References Newsgroups In-Reply-To Content-Type Reply-To Sender)])
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=195 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: * 3 FETCH (UID 6 RFC822.SIZE 2970 FLAGS (\Seen) BODY[HEADER.FIELDS (FROM TO CC SUBJECT DATE MESSAGE-ID PRIORITY X-PRIORITY REFERENCES NEWSGROUPS IN-REPLY-TO CONTENT-TYPE REPLY-TO SENDER)] {272}
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:STREAM:OPEN Size: 2970: Begin Message Download Stream
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=46 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: Message-ID: <[e-mail]>
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=39 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: Date: Wed, 23 May 2007 21:16:38 -0700
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=44 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: From: Bryce Nesbitt <[e-mail]>
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=44 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: To: Bryce Nesbitt <[e-mail]>
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=15 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: Subject: Test
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=32 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: Content-Type: multipart/mixed;
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=50 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket:  boundary="------------000206040108070504080807"
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=2 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: 
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=3 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: )
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:STREAM:CLOSE: Normal Message End Download Stream
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=24 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: 53 OK Fetch completed.
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:SendData: 54 IDLE
-1341256816[a6ef430]: ReadNextLine [stream=a6dad10 nb=10 needmore=0]
-1341256816[a6ef430]: a76d5e0:mail.idiom.com:S-BasiliX/Testing:CreateNewLineFromSocket: + idling


Next will come a failing example.

Comment 5

[Bryce Mozilla Nesbitt]

Attachment: Log of ineffective attempt to delete .mp3 attachment

Note that when first deleted, the attachment came in in large chunks.  After the "delete" it came in line by line.  Curious.

Comment 6

[Tony Mechelynck [:tonymec]]

Moving this to Core -> Networking:IMAP.

Since I don't use IMAP myself, I can't tell if this bug affects only the 1.8.1 branch, or the trunk too, so leaving Version=unspecified for the time being. David, what do you think?

Comment 7

[Nikolay Shopik]

version 3.0a2pre (2008052904)

Comment 9

[Wayne Mery (:wsmwk)]

Nikolay, had you been able to reproduce this.
Bryce seems to be gone, so if you can't reproduce, we might close this.

Comment 10

[Garret Wilson]

For what it's worth: for the latest versions attachment deletion has been working fine for me, even though I remember when I stumbled across this issue the same thing was happening to me---but that was years ago.

Comment 11

[Bryce Mozilla Nesbitt]

This was reported a long time ago.  I have not had a problem in the last few years.