[Kde-pim] Database error when syncing collections from KMail

[Kevin Krammer]

On Thursday, 2010-07-15, Kevin Krammer wrote:
> I am getting a weird database timeout error when syncing collections due to
> clicking on folders in KMail.

More debugging later it seems that an internal ItemFetchJob in ResourceBase is 
not returning.

See attached file for log and annotations

Cheers,
Kevin
-- 
Kevin Krammer, KDE developer, xdg-utils developer
KDE user support, developer mentoring
-------------- next part --------------
This is a log trace when item delivery works as expected

[akonadiserver] processing retrieval request for item 46  parts: ("RFC822")  of resource: "akonadi_mixedmaildir_resource_29"
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceBase::requestItemDelivery: uid= 46 remoteId= "0" parts= ("RFC822") scheduler.isEmpty= true
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceScheduler::scheduleItemFetch:
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceScheduler::scheduleItemFetch: idx= -1
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceScheduler::scheduleNext: mCurrentTask.type= 0
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceScheduler::scheduleNext: singleShot
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceScheduler::executeNext: mCurrentTask.type= 0 isEmpty= false
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceScheduler::executeNext: mCurrentTask.type= 4
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceBasePrivate::slotPrepareItemRetrieval:
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceBasePrivate::slotPrepareItemRetrievalResult: job->error= 0
 akonadi_mixedmaildir_resource_29(16758)/akonadiresource (maildir) MixedMaildirResource::retrieveItem: job= 0x911a098 item= 46 collection= "2000"
 akonadi_mixedmaildir_resource_29(16758)/akonadiresource (maildir) MixedMaildirResource::retrieveItemResult: job= 0x911a098 error= 0
 akonadi_mixedmaildir_resource_29(16758)/akonadiresource (maildir) MixedMaildirResource::retrieveItemResult: calling itemRetrieved with item 46
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceScheduler::scheduleNext: mCurrentTask.type= 0
[akonadiserver] continuing
[akonadiserver] checking if request for item 46 has been processed...
[akonadiserver] request for item 46 processed, error: ""


This is a log trace when it doesn't

[akonadiserver] processing retrieval request for item 46  parts: ("RFC822")  of resource: "akonadi_mixedmaildir_resource_29"
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceBase::requestItemDelivery: uid= 46 remoteId= "0" parts= ("RFC822") scheduler.isEmpty= true
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceScheduler::scheduleItemFetch:
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceScheduler::scheduleItemFetch: idx= -1
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceScheduler::scheduleNext: mCurrentTask.type= 3
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceScheduler::scheduleNext: mCurrentTask.type= 0
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceScheduler::scheduleNext: singleShot
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceScheduler::scheduleNext: mCurrentTask.type= 0
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceScheduler::scheduleNext: singleShot
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceScheduler::executeNext: mCurrentTask.type= 0 isEmpty= false
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceScheduler::executeNext: mCurrentTask.type= 4
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceBasePrivate::slotPrepareItemRetrieval:
 akonadi_mixedmaildir_resource_29(16758)/libakonadi Akonadi::ResourceScheduler::executeNext: mCurrentTask.type= 4 isEmpty= true
[akonadiserver] continuing
[akonadiserver] checking if request for item 46 has been processed...
[akonadiserver] request for item 46 processed, error: "Unable to retrieve item from resource: Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken."
[akonadiserver] ItemRetrieverException :  Unable to retrieve item from resource: Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken.

The step that does not appear as expected is Akonadi::ResourceBasePrivate::slotPrepareItemRetrievalResult

In Akonadiconsole's debuggger this looks like this:

150 UID FETCH 46 CACHEONLY ANCESTORS INF EXTERNALPAYLOAD (UID REMOTEID REMOTEREVISION COLLECTIONID FLAGS SIZE DATETIME)
* 46 FETCH (UID 46 REV 1 REMOTEID "0" MIMETYPE "message/rfc822" COLLECTIONID 504 SIZE 3865 DATETIME "16-Jul-2010 11:36:00 +0000" REMOTEREVISION "" FLAGS () ANCESTORS ((504 "2000") (462 "2000") (449 "GMX") (432 "Archiv") (431 "/dvl/Mail") (0 "")))

then there is a long pause (> 30 seconds) and then

150 OK UID FETCH completed

immediately followed by the next command.
-------------- next part --------------
A non-text attachment was scrubbed...
Name: signature.asc
Type: application/pgp-signature
Size: 190 bytes
Desc: This is a digitally signed message part.
URL: <http://mail.kde.org/pipermail/kde-pim/attachments/20100716/10cc4253/attachment.sig>
-------------- next part --------------
_______________________________________________
KDE PIM mailing list kde-pim at kde.org
https://mail.kde.org/mailman/listinfo/kde-pim
KDE PIM home page at http://pim.kde.org/