[Users] roughly 7-8 second delays for "Fetching message"---seems to be libravatar?

[George Avrunin]

On Sun, 9 Jun 2019 12:51:41 -0400, George Avrunin wrote:

> I use dovecot as the imap server on my office workstation, running Fedora
> 30.  When I read from home with claws (also on Fedora 30) and there are
> several unread messages in a folder, every few messages (not the same
> number each time), there will be a substantial delay.  (The same thing
> happens, though I think with a shorter delay, on the office workstation
> with claws connecting to localhost.) The delay is almost always about 7
> seconds (counting one-one thousand, ..., so not a precise measurement).
> While claws is saying "Fetching message", the message text window is blank
> and I can't do anything else in claws. Claws-mail is 3.17.3, dovecot is
> 2.3.4. Authentication is "automatic", using SSL/TLS for receive, on port
> 993. This started a few months ago, I think, but I've finally gotten
> annoyed enough (and had time) to ask for advice.
> 

Running claws-mail --debug and looking carefully at the log, the delays
all seem to be correlated with sequences of lines like the following:

libravatar_image.c:40:received 1535 bytes from avatar server
file-utils.c:58:TIMING safe_fclose : 0s000ms
libravatar_image.c:160:thread completed
textview.c:715:TIMING textview_add_part : 6s995ms

or

libravatar_image.c:153:waiting for thread completion
libravatar_image.c:107:retrieving URL to file:
http://cdn.libravatar.org/avatar/ 4975437b3ca0a769212d4ded95bf0580?s=48
-> /home/avrunin/.claws-mail/avatarcache/4 975437b3ca0a769212d4ded95bf0580
libravatar_image.c:40:received 1535 bytes from avatar server
file-utils.c:58:TIMING safe_fclose : 0s000ms
libravatar_image.c:160:thread completed
textview.c:715:TIMING textview_add_part : 6s995ms
textview.c:1207:Viewing text content of type: plain (length: 961)
textview.c:831:TIMING textview_add_part : 0s022ms
textview.c:848:TIMING recursive_add_parts : 0s022ms
textview.c:894:TIMING recursive_add_parts : 7s018ms
textview.c:654:TIMING textview_show_part : 7s018ms

or
 
libravatar_image.c:112:not enough data for an avatar image: 0 bytes
libravatar_missing.c:141:New md5 e5f582eabe53a881460202e70724fdb1 added
with time 1560184221 libravatar_image.c:160:thread completed

** (claws-mail:6808): WARNING **: 12:30:21.030: could not get image
textview.c:715:TIMING textview_add_part : 7s189ms
textview.c:681:TIMING textview_add_part : 0s000ms
textview.c:1207:Viewing text content of type: plain (length: 2239)
textview.c:831:TIMING textview_add_part : 0s029ms
textview.c:848:TIMING recursive_add_parts : 0s029ms
textview.c:894:TIMING recursive_add_parts : 0s029ms
textview.c:894:TIMING recursive_add_parts : 7s219ms
textview.c:654:TIMING textview_show_part : 7s219ms

I'm not sure whether this is normal behavior for the libravatar plugin or
a problem with my configuration, but I've unloaded the plugin and don't
seem to be encountering these delays any longer (albeit with a relatively
small test period).  

  George
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 228 bytes
Desc: OpenPGP digital signature
URL: <http://lists.claws-mail.org/pipermail/users/attachments/20190610/0191ed75/attachment.sig>