[prev in list] [next in list] [prev in thread] [next in thread] 

List:       kde-pim
Subject:    Re: [Kde-pim] Database error when syncing collections from KMail
From:       Kevin Krammer <kevin.krammer () gmx ! at>
Date:       2010-07-16 11:49:36
Message-ID: 201007161349.42731.kevin.krammer () gmx ! at
[Download RAW message or body]

[Attachment #2 (multipart/signed)]

[Attachment #4 (multipart/mixed)]


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

["log.txt" (text/plain)]

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.


["signature.asc" (application/pgp-signature)]

_______________________________________________
KDE PIM mailing list kde-pim@kde.org
https://mail.kde.org/mailman/listinfo/kde-pim
KDE PIM home page at http://pim.kde.org/

[prev in list] [next in list] [prev in thread] [next in thread]