326368 - Gets stuck in 100% CPU loop, must kill process

Status: RESOLVED DUPLICATE of bug 333877

(Thunderbird :: General, defect)

Description

[Greg Kreis]

User-Agent:       Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.8.0.1) Gecko/20060111 Firefox/1.5.0.1
Build Identifier: Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.8.0.1) Gecko/20060111 Firefox/1.5.0.1

Gets stuck using 100% of the CPU (50%-100% on an SMP machine) and must be killed.

Reproducible: Couldn't Reproduce




I use an IMAP server and the CPU hogging by TB happens on the desktop (SMP machine) and a laptop. No pattern yet as to what starts it.  Can go a day without it happening. These machines are not using the same anti-virus (one is AVG, the other NAV).

I used http://sysinternals.com/ procexp.exe program to capture some data. I have stack and thread data (text and screen captures) if this will help diagnose.  There are a couple of threads in the TB forum describing this problem, so others are seeing it.  I haven't seen it with machines configured for POP accounts.

The worst problem happens when you close TB and it sometimes causes Explorer to go into a 100% cpu usage situation.  The only solution is a reboot. (This doesn't always happen, however).

Comment 1

[WADA:World Anti-bad-Duping Agency]

(In reply to comment #0)
> The only solution is a reboot
Killing Thunderbird from Task Manager is impossible?
Starting Task Manager is impossible when problem occurs?

> Explorer to go into a 100% cpu usage situation
"Explorer" is "MS Windows Explore"(EXPLORER.EXE)?
If yes, why can the "100%" CPU of Explorer is bug of Thunderbird?

> Windows NT 5.1;
> on an SMP machine
> I use an IMAP server
> I haven't seen it with machines configured for POP accounts.

Which chip as "SMP"? MS Win XP? Which version of Thunderbird?

If MS Win XP on AMD/Intel acpi(or Intel's "speed step")/multi-core or Intel HyperThreading, see Bug 307527. The bug's phenomenon can be observed when POP3 & Tb 1.5(See Bug 315420), but number of bug reports when POP3 is small. And many users experienced Bug 307527 when IMAP & Tb 1.5. The timeout can not be observed externally when Tb 1.0.x, since mailnews.tcptimeout is not implemented when Tb 1.0.x.

TB 1.5? If yes, is there problem of Bug 307527 when desktop(SMP)?
TB 1.0.x? If yes, your problem when desktop(SMP) can be timer related problem when MS Win XP(or 2K) on acpi/multi-cpu.
In both cases, the problem can be bypassed by "/usepmtimer" in boot.ini or setting CPU affinitiy of Thunderbird to single CPU, if problem of MS Win XP on acpi/multi-cpu.
Can "/usepmtimer" be a workaround when Desktop(SMP)? (Note: SP2 is required.)  
(See MS knowledge-base pointed by Bug 307527 for detail.)

Above is not applicable to your laptop case(single CPU), then something wrong in   IMAP is suspectable, because you say problem only when IMAP.
See http://www.mozilla.org/quality/mailnews/mail-troubleshoot.html#pop ,
and get protocol log for IMAP(please dont'forget to change "protocol:5" to "IMAP:5"), then attach the log file to this bug(mime-type=text/plain).
Start Thunderbird with logging enabled every time. And restart Thunderbird after some hours or some days use in order to avoid disk full by log.

Comment 2

[Greg Kreis]

Attachment: procexp.exe performance metric tab

Comment 3

[Greg Kreis]

Attachment: procexp.exe thread metric snapshot 1

Comment 4

[Greg Kreis]

Attachment: procexp.exe thread metric snapshot 2

Comment 5

[David :Bienvenu]

is it possible that the connection to your imap server is getting dropped due to network/firewall issues? If so, you might be seeing bug 326314 though I don't know if that would cause high cpu usage.

Comment 6

[Greg Kreis]

Attachment: procexp.exe stacks from looping thread - snapshots at random

The thread 1 and thread 2 snapshots have the looping msvcrt.dll!endthreadex thread in common as the cpu hog. This attachment is group of stacks for that thread, taken at random as it chewed up the CPU.

Comment 7

[David :Bienvenu]

I think you must be missing some symbols, since nsFontList+0x36e6f is not very useful...i.e., the actual code is probably not font list code. Short of building TB yourself, I'm not sure how to get better symbols.

Comment 8

[Greg Kreis]

I've uploaded several attachments that show the procexp.exe stack traces, etc.  Hopefully they will offer a clue to those who can understand them.

Let me clarify my original posting (I was mistaken in saying you have to kill TB).

There is the initial problem, when TB 1.5 goes into the CPU hogging mode.  It still 'works', but it makes the machine sluggish and you can tell when it happens on the laptop due to the fan noise increase.  Seeing the thread captures from procexp.exe, it appears only one thread is hogging the CPU so perhaps that is why it keeps on working, but slower.

Most of the time when this happens, you can simply close the TB main window and it will stop.  You restart and everything seems to be okay then.  But sometimes, when you close the TB window, it stops TB, but it causes Windows Explorer to then go into a 100% CPU cycle.  Then you can only reboot to get Explorer to stop hogging the machine.  (So it seems that TB 1.5 is corrupting Explorer, FWIW.)

This behavior makes me think this is some kind of interaction between TB and Explorer.  Depending on when TB is stopped, it seems to leave Windows Explorer alone.  But sometimes it seems to have quit in a way that transfers the problem to Explorer.  (Others have reported this same behavior in the Mozilla Forums - search for TB and 100% CPU).

To answer the questions from the first comment.

This about TB 1.5 on an XP Pro SP2 machine (laptop or desktop). The SMP machine is a Tyan s2460 MOBO with dual AMD Athlon XP-M processors.  (My problem doesn't exhibit the problems described in 307527 - I had already read it.) The laptop is an Acer with Intel Pentimum M processor.  The laptop has 1 gig and the desktop has 1.5 gig of RAM.

Okay, I will start the logging process to see if I can catch it in the act.

Comment 9

[Greg Kreis]

What am I doing wrong?  I created this bat file and when start Tbird with it and get mail and then quit, I don't find a log file in the tmp folder.

set NSPR_LOG_MODULES=IMAP:5
set NSPR_LOG_FILE="c:\tmp\tbirdlog"
start thunderbird

Comment 10

[WADA:World Anti-bad-Duping Agency]

(In reply to comment #9)
> set NSPR_LOG_FILE="c:\tmp\tbirdlog"
(1)At least remove double-quote.
(2)Add extention for ease of file handling(".TXT" is best for editing, I think)
=> SET NSPR_LOG_FILE=C:\TMP\TBIRD.TXT

Comment 11

[Greg Kreis]

I removed the quotes and added a .txt (good suggestion).  It still did not appear on my drive, though I retrieved mail and sent a test message.

To help get more meaningful stack captures with procexp.exe, I hunted down symbol downloads from MS and configured them.  Now the stack captures appear to have more value.  Tbird was once again looping madly on one processor today, but the logging wasn't working and I didn't have the new symbols.  While I waited for symbols file to download (a large one from MS), the looping Tbird just quit on its own.  (I never let it loop for more than a minute or two before.  This time it ran for probably 10 minutes or more and then disappeared.  Time out?)

I'll keep you posted with more details, especially if I can find a way to get the log to work.

Thanks for you help!

Comment 12

[WADA:World Anti-bad-Duping Agency]

(In reply to comment #11)
> It still did not appear on my drive,
Did you create "C:\TMP" directory before execution?
Have you terminated Thunderbird?
Log is buffered and buffer is flushed when full and when termination.
> though I retrieved mail and sent a test message.
No log is taken for SMTP when IMAP:5 is specified.
If you want to get log for both IMAP and SMTP, specifiy both.
SET NSPR_LOG_MODULES=IMAP:5,SMTP:5

If you still fail to get log, try next bat.

C:
MD C:\TMP
SET NSPR_LOG_MODULES=IMAP:5
SET NSPR_LOG_FILE=C:\TMP\TBIRD.TXT
X: ("X:" is Your program directry drive for Thunderbird) 
CD "X:\<Your program directry for Thunderbird>"
thunderbird.exe -p 

If relation to timeout in IMAP is suspectable, set next.
SET NSPR_LOG_MODULES=IMAP:5,nsSocketTransport:5
See http://www.mozilla.org/projects/netlib/http/http-debugging.html
If timeout has really relation to your problem, get NSPR log with timestamp.
See Bug 86396 Comment #6 to #8 for NSPR log with timestamp when MS Win.

Comment 13

[Greg Kreis]

Tbird was looping again, before I could get the .bat file logging working.  (I made sure the log file directory existed, as you suggested, and that worked, but I did the experiment on my laptop and was moving the .bat to my desktop when, of course, it started looping there before I had it in place.)

Here are a few more stack snapshots of the runaway thread - msvcrt.dll!_endthreadex+0x3a (with the new symbols installed).  What is so interesting is that Tbird still works.  I can get and send mail, view it, etc.  So this thread is off doing something non-essential to the typical user. I let it 'go' for over 30 minutes to see if it would just end, like it did yesterday, but it didn't.  Fortunately it is winter time and my CPUs never get above 48c at full throttle... ;-)

ntoskrnl.exe!KiDispatchInterrupt+0xa7
ntdll.dll!RtlAllocateHeap+0x1a8
msvcrt.dll!_heap_alloc+0xe0
msvcrt.dll!_nh_malloc+0x13
msvcrt.dll!malloc+0x27
plc4.dll!PL_strdup+0x25
thunderbird.exe!nsFontList::Release+0x36f00
thunderbird.exe!nsFontList::Release+0x36e6f


ntoskrnl.exe!ExReleaseResourceLite+0x2b4
ntoskrnl.exe!IoPageRead+0x892
hal.dll!HalpApcInterrupt+0xc6
ntdll.dll!RtlLeaveCriticalSection+0x12
nspr4.dll!PR_MD_UNLOCK+0x20
thunderbird.exe!nsPrintSettings::SetPrintSilent+0x1acd
thunderbird.exe!nsFontList::Release+0x36f00
thunderbird.exe!nsFontList::Release+0x36e6f


ntoskrnl.exe!ExReleaseResourceLite+0x2b4
ntoskrnl.exe!IoPageRead+0x892
hal.dll!HalpApcInterrupt+0xc6
nspr4.dll!PR_EnterMonitor+0x10
thunderbird.exe!nsPrintSettings::SetPrintSilent+0x1acd
thunderbird.exe!nsFontList::Release+0x36f00
thunderbird.exe!nsFontList::Release+0x36e6f

Comment 14

[WADA:World Anti-bad-Duping Agency]

(In reply to comment #13)
> Tbird was looping again
Greg Kreis, try nexts.
 (1) Re-boot your MS Win with "Safe Mode" and login as "Administrator"
 (2) Re-boot again your MS Win and login as "Administrator",
     then start Thunderbird.     
 (3) Login as usual user, and start Thunderbird 
Does "loop before NSPR log is wrtten" still occur?
 (4) Create new profile and define the problematic IMAP account only.
Does the loop occur even when new profile/new account definition?

Comment 15

[Greg Kreis]

Thanks for the suggestions. I am going to be tied up for a week and a half, but I will be using my laptop so I'll see if I can pursue this investigation on it in the meantime.

P.S.
I believe I have discovered the reason for Explorer to be looping!  Perhaps it relates to Thunderbird's problem?  It was due to a shortcut to My Computer having been placed on the Start Menu by drag-n-drop.  The sysinternals Filemon app showed Explorer constantly accessing a target.lnk file in the My Computers folder in the Start Menu folder (it couldn't resolve the link).  Someone on the net figured out you needed to remove this link from the Start Menu.  Oiy... that was a mess to track down.  Now, I am wondering if Thunderbird was being messed up by this Explorer bug.  I'll let you know if the looping in TB continues.

Comment 16

[David :Bienvenu]

Interesting, could be...

Comment 17

[Greg Kreis]

I caught tbird looping again, right after I saved a message (which I have configured to save the draft in the draft folder on the IMAP server).  It did the save, but I noticed the looping started right after that.  I've embedded the last couple of operations from the Tbird IMAP log.  Does this give you any clues?

(I started TBird again - but only after I renamed the log to save it - and tried to get it to happen again by saving a message to the Draft folder and it didn't happen again - of course....)

INBOX:ProcessCurrentURL:imap://[e-mail]:993/addmsgflags%3EUID%3E.INBOX%3E19281%3E1:  = currentUrl
3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:SendData: 13 uid store 19281 +Flags (\Seen)

3924[63d56a8]: ReadNextLine [stream=635a278 nb=40 needmore=0]
3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:CreateNewLineFromSocket: * 2879 FETCH (UID 19281 FLAGS (\Seen))

3924[63d56a8]: ReadNextLine [stream=635a278 nb=24 needmore=0]
3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:CreateNewLineFromSocket: 13 OK STORE completed.

3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:SendData: 14 IDLE

3924[63d56a8]: ReadNextLine [stream=635a278 nb=22 needmore=0]
3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:CreateNewLineFromSocket: + entering idle mode

3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:SendData: DONE

3924[63d56a8]: ReadNextLine [stream=635a278 nb=22 needmore=0]
3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:CreateNewLineFromSocket: 14 OK IDLE completed

3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:ProcessCurrentURL: entering
3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:ProcessCurrentURL:imap://[e-mail]:993/subtractmsgflags%3EUID%3E.INBOX%3E19281%3E1:  = currentUrl
3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:SendData: 15 uid store 19281 -Flags (\Seen)

3924[63d56a8]: ReadNextLine [stream=635a278 nb=35 needmore=0]
3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:CreateNewLineFromSocket: * 2879 FETCH (UID 19281 FLAGS ())

3924[63d56a8]: ReadNextLine [stream=635a278 nb=24 needmore=0]
3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:CreateNewLineFromSocket: 15 OK STORE completed.

3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:SendData: 16 IDLE

3924[63d56a8]: ReadNextLine [stream=635a278 nb=22 needmore=0]
3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:CreateNewLineFromSocket: + entering idle mode

3776[27a0760]: ReadNextLine [stream=36cb4a8 nb=40 needmore=0]
3776[27a0760]: 6ac2010:imap.emailsrvr.com:S-INBOX:CreateNewLineFromSocket: * 2877 FETCH (FLAGS (\Answered \Seen))

3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:SendData: DONE

3924[63d56a8]: ReadNextLine [stream=635a278 nb=22 needmore=0]
3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:CreateNewLineFromSocket: 16 OK IDLE completed

3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:ProcessCurrentURL: entering
3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:ProcessCurrentURL:imap://[e-mail]:993/select%3E.INBOX:  = currentUrl
3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:SendData: 17 noop

3924[63d56a8]: ReadNextLine [stream=635a278 nb=22 needmore=0]
3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:CreateNewLineFromSocket: 17 OK NOOP completed

3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:SendData: 18 getquotaroot "INBOX"

3924[63d56a8]: ReadNextLine [stream=635a278 nb=28 needmore=0]
3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:CreateNewLineFromSocket: * QUOTAROOT "INBOX" "ROOT"

3924[63d56a8]: ReadNextLine [stream=635a278 nb=36 needmore=0]
3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:CreateNewLineFromSocket: * QUOTA "ROOT" (STORAGE 0 1048576)

3924[63d56a8]: ReadNextLine [stream=635a278 nb=24 needmore=0]
3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:CreateNewLineFromSocket: 18 OK GETQUOTAROOT Ok.

3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:SendData: 19 UID fetch 19282:* (FLAGS)

3924[63d56a8]: ReadNextLine [stream=635a278 nb=24 needmore=0]
3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:CreateNewLineFromSocket: 19 OK FETCH completed.

3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:SendData: 20 IDLE

3924[63d56a8]: ReadNextLine [stream=635a278 nb=22 needmore=0]
3924[63d56a8]: 3cf1078:imap.emailsrvr.com:S-INBOX:CreateNewLineFromSocket: + entering idle mode

0[274830]: 6de1200:imap.emailsrvr.com:A:TellThreadToDie: close socket connection
304[63d5560]: ImapThreadMainLoop leaving [this=6de1200]
0[274830]: 6987018:imap.emailsrvr.com:S-INBOX.Sent:TellThreadToDie: close socket connection
0[274830]: 6de1200:imap.emailsrvr.com:NA:SetupWithUrl: clearing IMAP_CONNECTION_IS_OPEN
1712[63d5560]: ImapThreadMainLoop entering [this=6de1200]
1712[63d5560]: 6de1200:imap.emailsrvr.com:NA:ProcessCurrentURL: entering
1712[63d5560]: 6de1200:imap.emailsrvr.com:NA:ProcessCurrentURL:imap://[e-mail]:993/appenddraftfromfile%3E.INBOX.Drafts%3EUID%3E:  = currentUrl
1712[63d5560]: ReadNextLine [stream=626ca98 nb=58 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:NA:CreateNewLineFromSocket: * OK IMAP4 Ready director1.r1.iad.emailsrvr.com 00022090

1712[63d5560]: 6de1200:imap.emailsrvr.com:NA:SendData: Logging suppressed for this command (it probably contained authentication information)
1712[63d5560]: ReadNextLine [stream=626ca98 nb=16 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:NA:CreateNewLineFromSocket: 1 OK LOGIN Ok.

1712[63d5560]: 6de1200:imap.emailsrvr.com:A:SendData: 2 select "INBOX.Drafts"

1712[63d5560]: ReadNextLine [stream=626ca98 nb=60 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:A:CreateNewLineFromSocket: * FLAGS (\Draft \Answered \Flagged \Deleted \Seen \Recent)

1712[63d5560]: ReadNextLine [stream=626ca98 nb=77 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:A:CreateNewLineFromSocket: * OK [PERMANENTFLAGS (\* \Draft \Answered \Flagged \Deleted \Seen)] Limited

1712[63d5560]: ReadNextLine [stream=626ca98 nb=12 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:A:CreateNewLineFromSocket: * 5 EXISTS

1712[63d5560]: ReadNextLine [stream=626ca98 nb=12 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:A:CreateNewLineFromSocket: * 0 RECENT

1712[63d5560]: ReadNextLine [stream=626ca98 nb=34 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:A:CreateNewLineFromSocket: * OK [UIDVALIDITY 1100377580] Ok

1712[63d5560]: ReadNextLine [stream=626ca98 nb=32 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:A:CreateNewLineFromSocket: * OK [MYRIGHTS "acdilrsw"] ACL

1712[63d5560]: ReadNextLine [stream=626ca98 nb=22 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:A:CreateNewLineFromSocket: 2 OK [READ-WRITE] Ok

1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:SendData: 3 getquotaroot "INBOX.Drafts"

1712[63d5560]: ReadNextLine [stream=626ca98 nb=35 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:CreateNewLineFromSocket: * QUOTAROOT "INBOX.Drafts" "ROOT"

1712[63d5560]: ReadNextLine [stream=626ca98 nb=36 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:CreateNewLineFromSocket: * QUOTA "ROOT" (STORAGE 0 1048576)

1712[63d5560]: ReadNextLine [stream=626ca98 nb=23 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:CreateNewLineFromSocket: 3 OK GETQUOTAROOT Ok.

1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:SendData: 4 UID fetch 1:* (FLAGS)

1712[63d5560]: ReadNextLine [stream=626ca98 nb=30 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:CreateNewLineFromSocket: * 1 FETCH (UID 223 FLAGS ())

1712[63d5560]: ReadNextLine [stream=626ca98 nb=30 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:CreateNewLineFromSocket: * 2 FETCH (UID 226 FLAGS ())

1712[63d5560]: ReadNextLine [stream=626ca98 nb=30 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:CreateNewLineFromSocket: * 3 FETCH (UID 228 FLAGS ())

1712[63d5560]: ReadNextLine [stream=626ca98 nb=35 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:CreateNewLineFromSocket: * 4 FETCH (UID 232 FLAGS (\Seen))

1712[63d5560]: ReadNextLine [stream=626ca98 nb=44 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:CreateNewLineFromSocket: * 5 FETCH (UID 251 FLAGS (\Seen \Deleted))

1712[63d5560]: ReadNextLine [stream=626ca98 nb=23 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:CreateNewLineFromSocket: 4 OK FETCH completed.

1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:SendData: 5 append "INBOX.Drafts" (\Seen) {987}

1712[63d5560]: ReadNextLine [stream=626ca98 nb=6 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:CreateNewLineFromSocket: + OK

1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:SendData: FCC: imap://[e-mail]/INBOX/Sent

X-Identity-Key: id3

Message-ID: <[e-mail]>

Date: Sat, 18 Feb 2006 20:22:59 -0500

From: Greg Kreis <[e-mail]>

X-Mozilla-Draft-Info: internal/draft; vcard=0; receipt=0; uuencode=0

User-Agent: Thunderbird 1.5 (Windows/20051201)

MIME-Version: 1.0

To: Heather Kreis <[e-mail]>, 

1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:SendData: 

1712[63d5560]: ReadNextLine [stream=626ca98 nb=44 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:CreateNewLineFromSocket: 5 OK [APPENDUID 1100377580 252] APPEND Ok.

1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:SendData: 6 noop

1712[63d5560]: ReadNextLine [stream=626ca98 nb=12 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:CreateNewLineFromSocket: * 6 EXISTS

1712[63d5560]: ReadNextLine [stream=626ca98 nb=12 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:CreateNewLineFromSocket: * 0 RECENT

1712[63d5560]: ReadNextLine [stream=626ca98 nb=21 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:CreateNewLineFromSocket: 6 OK NOOP completed

1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:SendData: 7 IDLE

1712[63d5560]: ReadNextLine [stream=626ca98 nb=22 needmore=0]
1712[63d5560]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:CreateNewLineFromSocket: + entering idle mode

0[274830]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:SendData: DONE

0[274830]: ReadNextLine [stream=626ca98 nb=21 needmore=0]
0[274830]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:CreateNewLineFromSocket: 7 OK IDLE completed

0[274830]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:SendData: 8 close

0[274830]: ReadNextLine [stream=626ca98 nb=22 needmore=0]
0[274830]: 6de1200:imap.emailsrvr.com:S-INBOX.Drafts:CreateNewLineFromSocket: 8 OK mailbox closed.

0[274830]: 6de1200:imap.emailsrvr.com:A:SendData: 9 logout

0[274830]: ReadNextLine [stream=626ca98 nb=41 needmore=0]
0[274830]: 6de1200:imap.emailsrvr.com:A:CreateNewLineFromSocket: * BYE Courier-IMAP server shutting down

0[274830]: 6de1200:imap.emailsrvr.com:NA:TellThreadToDie: close socket connection
0[274830]: 6ac2010:imap.emailsrvr.com:S-INBOX:TellThreadToDie: close socket connection
0[274830]: 3cf1078:imap.emailsrvr.com:S-INBOX:TellThreadToDie: close socket connection
1712[63d5560]: ImapThreadMainLoop leaving [this=6de1200]
3776[27a0760]: ImapThreadMainLoop leaving [this=6ac2010]
3924[63d56a8]: ImapThreadMainLoop leaving [this=3cf1078]

Comment 18

[Greg Kreis]

FWIW, I think I have found a work around.

Tools--> Account Settings --> Server Settings --> Advanced

I unchecked the "Use IDLE Command if the server supports it". Since I did this, I have not noticed the run away CPU.  Someone needs to investigate how this feature reacts to a server that doesn't support IDLE.

Comment 19

[Wayne Mery (:wsmwk)]

shades of Bug 236708?  (Bug 236708 comment 0, etc)

Comment 20

[Wayne Mery (:wsmwk)]

David, comment 17 has a log from the loop, but not from *before* where the looping started.  Do you need that as well?

Comment 21

[Rolf Leggewie]

maybe this is bug 228201?

Comment 22

[Wayne Mery (:wsmwk)]

(In reply to comment #21)
> maybe this is bug 228201?

or IMO more likely Bug 333877 which was just fixed.
the trunk build needs a test drive.

Comment 23

[Greg Kreis]

I have not been able to get this problem to resurface when I change the server settings to 'use IDLE if supported by server'.  I don't know if this is because I am running TB v1.5.0.5 now or what might be the cause.  Anyway, I am closing it out now.

*** This bug has been marked as a duplicate of 333877 ***