Here is the versions of evolution we are running under Ubuntu 10.04 64bit.

dpkg -l | grep evolution
ii  evolution                                                  
2.28.3-0ubuntu10.3                                            groupware
suite with mail client and organiz
ii  evolution-common                                            
2.28.3-0ubuntu10.3                                            
architecture independent files for Evolution
ii  evolution-data-server                                      
2.28.3.1-0ubuntu6                                             evolution
database backend server
ii  evolution-data-server-common                                
2.28.3.1-0ubuntu6                                            
architecture independent files for Evolution
ii  evolution-plugins                                          
2.28.3-0ubuntu10.3                                            standard
plugins for Evolution
ii  evolution-webcal                                            
2.28.0-1                                                      webcal:
URL handler for GNOME and Evolution
ii  libebackend1.2-0                                            
2.28.3.1-0ubuntu6                                             Utility
library for evolution data servers
rc  libebook1.2-10                                              
2.32.2-0ubuntu2~lucid1~ppa2                                   Client
library for evolution address books
ii  libebook1.2-9                                              
2.28.3.1-0ubuntu6                                             Client
library for evolution address books
ii  libecal1.2-7                                                
2.28.3.1-0ubuntu6                                             Client
library for evolution calendars
rc  libecal1.2-8                                                
2.32.2-0ubuntu2~lucid1~ppa2                                   Client
library for evolution calendars
ii  libedata-book1.2-2                                          
2.28.3.1-0ubuntu6                                             Backend
library for evolution address books
rc  libedata-book1.2-8                                          
2.32.2-0ubuntu2~lucid1~ppa2                                   Backend
library for evolution address books
rc  libedata-cal1.2-10                                          
2.32.2-0ubuntu2~lucid1~ppa2                                   Backend
library for evolution calendars
ii  libedata-cal1.2-6                                          
2.28.3.1-0ubuntu6                                             Backend
library for evolution calendars
ii  libedataserver1.2-11                                        
2.28.3.1-0ubuntu6                                             Utility
library for evolution data servers
rc  libedataserver1.2-14                                        
2.32.2-0ubuntu2~lucid1~ppa2                                   Utility
library for evolution data servers
rc  libedataserverui1.2-11                                      
2.32.2-0ubuntu2~lucid1~ppa2                                   GUI
utility library for evolution data serve
ii  libedataserverui1.2-8                                      
2.28.3.1-0ubuntu6                                             GUI
utility library for evolution data serve
ii  python-evolution                                            
2.30.0-0ubuntu1.1                                             Python
bindings for the evolution libraries

When I run evolution with the debug logging command:

CAMEL_DEBUG=all evolution>&  evo.log

CamelStore('imap://dean%40dcre.com...@mail.dcre.com.au/;check_lsub;use_ssl=always;command=ssh%20-C%20-l%20%25u%2 0%25h%20exec%20/usr/sbin/imapd;check_all;imap_custom_headers'):get_folder(' Sent',
0) = 0x7f0ccc1faee0
< 0 >
Thread 0 >
CamelFolder:get_message('INBOX', '11840') =
class: CamelMimeMessage
mime-type: multipart/alternative;
     boundary="Apple-Mail=_BC57DBAA-6019-436C-9EB7-5B5FFFF1F238"
content class: CamelMultipart
content mime-type: multipart/alternative;
     boundary="Apple-Mail=_BC57DBAA-6019-436C-9EB7-5B5FFFF1F238"
   class: CamelMimePart
   mime-type: text/plain; charset="us-ascii"
   content class: CamelDataWrapper
   content mime-type: text/plain; charset="us-ascii"
   class: CamelMimePart
   mime-type: multipart/mixed;
     boundary="Apple-Mail=_DEE19FDE-C80D-460D-A943-49262FF9A35C"
   content class: CamelMultipart
   content mime-type: multipart/mixed;
     boundary="Apple-Mail=_DEE19FDE-C80D-460D-A943-49262FF9A35C"
     class: CamelMimePart
     mime-type: text/html; charset="us-ascii"
     content class: CamelDataWrapper
     content mime-type: text/html; charset="us-ascii"
     class: CamelMimePart
     mime-type: image/tiff; name="PastedGraphic-1.tiff"
     content class: CamelDataWrapper
     content mime-type: image/tiff; name="PastedGraphic-1.tiff"
     class: CamelMimePart
     mime-type: text/html; charset="us-ascii"
     content class: CamelDataWrapper
     content mime-type: text/html; charset="us-ascii"
     class: CamelMimePart
     mime-type: application/msword; name="Year End Audit Date Change
DCRE.doc"
     content class: CamelDataWrapper
     content mime-type: application/msword; name="Year End Audit Date
Change DCRE.doc"
     class: CamelMimePart
     mime-type: text/html; charset="us-ascii"
     content class: CamelDataWrapper
     content mime-type: text/html; charset="us-ascii"
< 0 >

camel_db_select:
SELECT uid, flags, size, dsent, dreceived, subject, mail_from, mail_to,
mail_cc, mlist, part, labels, usertags, cinfo, bdata FROM 'Sent'

===========
DB SQL operation [SELECT uid, flags, size, dsent, dreceived, subject,
mail_from, mail_to, mail_cc, mlist, part, labels, usertags, cinfo, bdata
FROM 'Sent' ] started
DB Operation ended. Time Taken : 0.002316
###########
sending : A00027 STATUS Sent (MESSAGES UNSEEN)
received: * STATUS "Sent" (MESSAGES 119 UNSEEN 0)
received: A00027 OK STATUS completed

camel_db_select:
SELECT * FROM folders WHERE folder_name = 'Trash'

===========
DB SQL operation [SELECT * FROM folders WHERE folder_name = 'Trash'] started

read_fir_callback called
DB Operation ended. Time Taken : 0.000230
###########

camel_db_select:
SELECT uid,flags FROM 'Trash'

===========
DB SQL operation [SELECT uid,flags FROM 'Trash'] started
DB Operation ended. Time Taken : 0.000084
###########

camel_db_select:
SELECT vuid FROM '.#evolution/Trash' WHERE vuid LIKE 'ToScbWPb%'

===========
DB SQL operation [SELECT vuid FROM '.#evolution/Trash' WHERE vuid LIKE
'ToScbWPb%'] started
DB Operation ended. Time Taken : 0.000083
###########
sexp is : [(match-all (system-flag "Deleted"))]
Equivalent sql SELECT uid FROM 'Trash' WHERE (deleted = 1)

camel_db_select:
SELECT uid FROM 'Trash' WHERE (deleted = 1)

===========
DB SQL operation [SELECT uid FROM 'Trash' WHERE (deleted = 1)] started
DB Operation ended. Time Taken : 0.000087
###########
vee_rebuild_folder(.#evolution/Trash <- Trash ToScbWPb): match 0,
correlating 0, rebuilded 1

===========
DB SQL operation [BEGIN] started
Camel SQL Exec:
BEGIN
Camel SQL Exec:
COMMIT
DB Operation ended. Time Taken : 0.000093
###########

camel_db_select:
SELECT vuid FROM '.#evolution/Junk' WHERE vuid LIKE 'ToScbWPb%'

===========
DB SQL operation [SELECT vuid FROM '.#evolution/Junk' WHERE vuid LIKE
'ToScbWPb%'] started
DB Operation ended. Time Taken : 0.000086
###########
sexp is : [(match-all (system-flag "Junk"))]
Equivalent sql SELECT uid FROM 'Trash' WHERE (junk = 1)

camel_db_select:
SELECT uid FROM 'Trash' WHERE (junk = 1)

===========
DB SQL operation [SELECT uid FROM 'Trash' WHERE (junk = 1)] started
DB Operation ended. Time Taken : 0.000085
###########
vee_rebuild_folder(.#evolution/Junk <- Trash ToScbWPb): match 0,
correlating 0, rebuilded 1

===========
DB SQL operation [BEGIN] started
Camel SQL Exec:
BEGIN
Camel SQL Exec:
COMMIT
DB Operation ended. Time Taken : 0.000091
###########
sending : A00028 SELECT Trash
received: * 1 EXISTS
received: * 0 RECENT
received: * OK [UIDVALIDITY 1] UIDs are valid for this mailbox
received: * OK [UIDNEXT 14881] next expected UID is 14881
received: * FLAGS (\Answered \Deleted \Draft \Flagged \Seen $Forwarded
$MDNSent Forwarded $Junk $NotJunk Junk JunkRecorded NonJunk NotJunk
receipt-handled $label1 $has_cal)
received: * OK [PERMANENTFLAGS (\Answered \Deleted \Draft \Flagged \Seen
$Forwarded $MDNSent Forwarded receipt-handled $label1 $has_cal \*)]
junk-related flags are not permanent
received: * OK [HIGHESTMODSEQ 164000] modseq tracked on this mailbox
received: A00028 OK [READ-WRITE] SELECT completed
sending : A00029 UID FETCH 1:14880 (FLAGS)
received: * 1 FETCH (UID 14880 FLAGS (\Seen))
received: A00029 OK UID FETCH completed
Thread 0 >
CamelStore('imap://dean%40dcre.com...@mail.dcre.com.au/;check_lsub;use_ssl=always;command=ssh%20-C%20-l%20%25u%2 0%25h%20exec%20/usr/sbin/imapd;check_all;imap_custom_headers'):get_folder(' Trash',
0) = 0x7f0ccc1ff800
< 0 >

camel_db_select:
SELECT uid, flags, size, dsent, dreceived, subject, mail_from, mail_to,
mail_cc, mlist, part, labels, usertags, cinfo, bdata FROM 'Trash' WHERE
uid = '14880'

===========
DB SQL operation [SELECT uid, flags, size, dsent, dreceived, subject,
mail_from, mail_to, mail_cc, mlist, part, labels, usertags, cinfo, bdata
FROM 'Trash' WHERE uid = '14880'] started
DB Operation ended. Time Taken : 0.000189
###########
sending : A00030 STATUS Trash (MESSAGES UNSEEN)
received: * STATUS "Trash" (MESSAGES 1 UNSEEN 0)
received: A00030 OK STATUS completed

I've tried upgrading Evolution to 2.32 but it will not even connect to
the mail server after ...

read more »

On 19/03/12 10:30, Michael Pope wrote:

Is it a "everything just locks up with no CPU usage" kind of crash or
something else?

If it's completely locked up and there's no *evolution* processes using
a lot of CPU I'd wager on getting something useful out of:
$ strace -f evolution > evo.strace 2>&1

I've seen one of the mail clients (I can't remember if it was evolution)
shelling out to a poorly configured mail filter that made the whole mail
client appear to lock up, which I eventually diagnosed with strace.

So... might help.

Cheers,
Dave

signature.asc < 1K Download

On 19/03/12 22:26, David Schoen wrote:

I ran the strace and it gave me a lot of info.

I managed to open up an email from that user (one without an
attachment). I think the problem is the attached word document. I've
also opened up an email from the same person with two attachments a pdf
and tiff file.

We run crossover here with MS Office which could be a problem.

It could be something in this file:
[pid 31554] open("/usr/share/mime/application/msword.xml", O_RDONLY
<unfinished ...>
[pid 31406] <... stat resumed> 0x7fffbed6eef0) = -1 ENOENT (No such file
or directory)
[pid 31554] <... open resumed> )        = 40

Here are the bottom few lines of the strace before evolution froze. I
check and it wasn't using much CPU, it was just waiting for something.
[pid 31554] futex(0x1344fc0, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 31554] futex(0x1208804, FUTEX_WAIT_PRIVATE, 1, NULL <unfinished ...>
[pid 31406] read(3, 0xa672d4, 4096)     = -1 EAGAIN (Resource
temporarily unavailable)
[pid 31406] poll([{fd=4, events=POLLIN}, {fd=3, events=POLLIN}, {fd=8,
events=POLLIN|POLLPRI}, {fd=12, events=POLLIN|POLLPRI}, {fd=13,
events=POLLIN|POLLPRI}, {fd=14, events=POLLIN|POLLPRI}, {fd=10,
events=POLLIN|POLLPRI}, {fd=17, events=POLLIN|POLLPRI}, {fd=16,
events=POLLIN|POLLPRI}, {fd=38, events=POLLIN}, {fd=39, events=POLLIN},
{fd=42, events=POLLIN}, {fd=15, events=POLLIN}], 13, 0) = 0 (Timeout)
[pid 31406] poll([{fd=3, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=3,
revents=POLLOUT}])
[pid 31406] writev(3, [{"&\7\2\0\231\0 \5", 8}, {NULL, 0}, {"", 0}], 3) = 8
[pid 31406] poll([{fd=3, events=POLLIN}], 1, -1) = 1 ([{fd=3,
revents=POLLIN}])
[pid 31406] read(3, "\16\0\252\201\231\0
\5\0\0>\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096) = 128
[pid 31406] read(3, 0xa672d4, 4096)     = -1 EAGAIN (Resource
temporarily unavailable)
[pid 31406] poll([{fd=3, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=3,
revents=POLLOUT}])
[pid 31406] writev(3, [{"5\30\4\0\266\32 \5\231\0
\5;\3C\3\225\4\5\0\267\32 \5\266\32 \5*\0\0\0"..., 16288},
{"\0\0\0\0\0'\0\0\0\0\2\0\0\0\0\0\0007\1\0\0'\0\0\0\0;\0\0\0\0\0"...,
200}, {"", 0}], 3) = 16488
[pid 31406] lstat("/usr/share/icons/Humanity/mimes/16/image-tiff.svg",
{st_mode=S_IFLNK|0777, st_size=19, ...}) = 0
[pid 31406] stat("/usr/share/icons/Humanity/mimes/16/image-tiff.svg",
{st_mode=S_IFREG|0644, st_size=3050, ...}) = 0
[pid 31406]
readlink("/usr/share/icons/Humanity/mimes/16/image-tiff.svg",
"image-x-generic.svg", 256) = 19
[pid 31406] open("/usr/share/icons/Humanity/mimes/16/image-tiff.svg",
O_RDONLY) = 40
[pid 31406] fstat(40, {st_mode=S_IFREG|0644, st_size=3050, ...}) = 0
[pid 31406] read(40, "<?xml version=\"1.0\" encoding=\"UT"..., 65536) = 3050
[pid 31406] futex(0x7f9e8bc00488, FUTEX_WAIT_PRIVATE, 2, NUL

I've got the whole strace here and had a look around but cannot see
anything obvious.

I think it's waiting for crossover to load up and virus scan the file or
something.

from
Michael

On 21/03/12 10:18, Michael Pope wrote:

I personally haven't had much success when something is halting on a
futex (but then again only one of our apps seemed to use them in places
it would commonly fall over and we replaced it with something else).

The futex implies that pid 31406 is waiting for something else. I think
you'll need to go searching for the 0x7f9e8bc00488 address through the
strace output to see what other pids it's used in and what they're doing
shortly after a FUTEX_WAKE that may be stalling indefinitely... however
not having found success this way in practice lets hope someone else
pipes up :)

There's also meant to be some way of pulling info about who's using a
futex out of /proc (or maybe /sys?) but I can't seem to find the article
I learnt that from.

Cheers,
Dave

signature.asc < 1K Download