[Kde-pim] One IMAP resource lost all its folders

David Faure faure at kde.org
Fri Apr 10 13:52:58 BST 2015


The resource was working normally for a very long time....

13:58:01 akonadi_imap_resource_4(12096)/libakonadi Akonadi::ItemSync::setIncrementalSyncItems: Received:  1 Removed:  0 In total:  1  Wanted:  1
13:58:01 akonadi_imap_resource_4(12096)/kdepimlibs (kimap) RetrieveItemsTask::listFlagsForImapSet: Listing flags  1 18609
13:58:01 akonadi_imap_resource_4(12096)/kdepimlibs (kimap) RetrieveItemsTask::listFlagsForImapSet: Starting flag retrieval. Elapsed(ms):  747
13:58:01 akonadi_imap_resource_4(12096)/kdepimlibs (kimap) BatchFetcher::fetchNextBatch: Fetching all messages in one batch.
13:58:02 akonadi_imap_resource_4(12096)/kdepimlibs (kimap) RetrieveItemsTask::taskComplete: Applying collection changes
13:58:02 akonadi_imap_resource_4(12096)/libakonadi Akonadi::ItemSync::setIncrementalSyncItems: Received:  0 Removed:  0 In total:  1  Wanted:  1
13:58:02 akonadi_imap_resource_4(12096)/kdepimlibs (kimap) RetrieveItemsTask::taskComplete: Retrieval complete. Elapsed(ms):  906
13:58:02 akonadi_imap_resource_4(12096) ResourceTask::~ResourceTask: ResourceTask(0x229f6f0) releasing session KIMAP::Session(0x2446f60)
13:58:02 akonadi_imap_resource_4(12096)/libakonadi Akonadi::ProtocolHelper::streamPayloadToFile: Wrote 4254 bytes to  "/home/dfaure/.local/share/akonadi/file_db_data/6102581_r0"
13:58:02 akonadi_imap_resource_4(12096)/libakonadi Akonadi::ProtocolHelper::streamPayloadToFile: Wrote 4908 bytes to  "/home/dfaure/.local/share/akonadi/file_db_data/6102583_r0"
13:58:02 akonadi_imap_resource_4(12096) ImapResourceBase::startTask:
13:58:02 akonadi_imap_resource_4(12096) ResourceTask::start: Starting task RetrieveCollectionMetadataTask(0x22a4c30)
13:58:02 akonadi_imap_resource_4(12096) SessionPool::requestSession: pending requests: (2342)
13:58:02 akonadi_imap_resource_4(12096) SessionPool::processPendingRequests: session ready: 2342 KIMAP::Session(0x2446f60)
13:58:02 akonadi_imap_resource_4(12096) ResourceTask::onSessionRequested: task RetrieveCollectionMetadataTask(0x22a4c30) session request says 2342 0 ""
13:58:02 akonadi_imap_resource_4(12096)/kdepimlibs (kimap) RetrieveCollectionMetadataTask::doStart: "/kde-pim"
13:58:02 akonadi_imap_resource_4(12096) RetrieveCollectionMetadataTask::onGetAclDone: GetACL failed:  "GetAcl failed, server replied: A000906 NO Permission denied " 
13:58:02 akonadi_imap_resource_4(12096) ResourceTask::~ResourceTask: ResourceTask(0x22a4c30) releasing session KIMAP::Session(0x2446f60)

(it's a shared folder from someone else, getacl fails everytime, no problem there.)

14:01:37 akonadi_imap_resource_4(12096) ImapResourceBase::retrieveCollections:
14:01:37 akonadi_imap_resource_4(12096) ImapResourceBase::startTask:
14:01:37 akonadi_imap_resource_4(12096) ResourceTask::start: Starting task RetrieveCollectionsTask(0x22f6550)
14:01:37 akonadi_imap_resource_4(12096) SessionPool::requestSession: pending requests: (2343)
14:01:37 akonadi_imap_resource_4(12096) SessionPool::processPendingRequests: session ready: 2343 KIMAP::Session(0x2446f60)
14:01:37 akonadi_imap_resource_4(12096) ResourceTask::onSessionRequested: task RetrieveCollectionsTask(0x22f6550) session request says 2343 0 ""
14:01:47 akonadi_imap_resource_4(12096)/libakonadi Akonadi::CollectionSync::Private::execute: void Akonadi::CollectionSync::Private::execute() localListDone:  false  deliveryDone:  true
14:01:47 akonadi_imap_resource_4(12096) ResourceTask::~ResourceTask: ResourceTask(0x22f6550) releasing session KIMAP::Session(0x2446f60)
14:01:47 akonadi_imap_resource_4(12096)/libakonadi Akonadi::CollectionSync::Private::execute: void Akonadi::CollectionSync::Private::execute() localListDone:  true  deliveryDone:  true
14:01:47 akonadi_imap_resource_4(12096)/libakonadi Akonadi::CollectionSync::Private::execute: void Akonadi::CollectionSync::Private::execute() localListDone:  true  deliveryDone:  true
14:02:22 akonadi_imap_resource_4(12096)/libakonadi Akonadi::CollectionSync::Private::checkDone: void Akonadi::CollectionSync::Private::checkDone() q->commit()
14:02:22 akonadi_imap_resource_4(12096)/libakonadi Akonadi::ResourceBasePrivate::slotAttributeRetrievalCollectionFetchDone: Failed to retrieve collection for attribute sync:  "Collection 6423 does not exist" 
14:02:22 akonadi_imap_resource_4(12096)/libakonadi Akonadi::ResourceBasePrivate::slotAttributeRetrievalCollectionFetchDone: Failed to retrieve collection for attribute sync:  "Collection 6418 does not exist" 
14:02:22 akonadi_imap_resource_4(12096)/libakonadi Akonadi::ResourceBasePrivate::slotAttributeRetrievalCollectionFetchDone: Failed to retrieve collection for attribute sync:  "Collection 6417 does not exist" 
14:02:22 akonadi_imap_resource_4(12096)/libakonadi Akonadi::ResourceBasePrivate::slotAttributeRetrievalCollectionFetchDone: Failed to retrieve collection for attribute sync:  "Collection 6415 does not exist" 
14:02:22 akonadi_imap_resource_4(12096)/libakonadi Akonadi::ResourceBasePrivate::slotAttributeRetrievalCollectionFetchDone: Failed to retrieve collection for attribute sync:  "Collection 6414 does not exist" 

Where did my collections go?

14:02:22 akonadi_imap_resource_4(12096) ImapResourceBase::startTask:
14:02:22 akonadi_imap_resource_4(12096) ResourceTask::start: Starting task RetrieveCollectionMetadataTask(0x21cc1e0)
14:02:22 akonadi_imap_resource_4(12096) SessionPool::requestSession: pending requests: (2344)
14:02:22 akonadi_imap_resource_4(12096) SessionPool::processPendingRequests: session ready: 2344 KIMAP::Session(0x2446f60)
14:02:22 akonadi_imap_resource_4(12096) ResourceTask::onSessionRequested: task RetrieveCollectionMetadataTask(0x21cc1e0) session request says 2344 0 ""
14:02:22 akonadi_imap_resource_4(12096)/kdepimlibs (kimap) RetrieveCollectionMetadataTask::doStart: "imap://david.faure@ipsquad.mykolab.org@ipsquad.mykolab.org/"
14:02:22 akonadi_imap_resource_4(12096)/kdepimlibs (kimap) RetrieveCollectionMetadataTask::doStart: No Select folder
14:02:22 akonadi_imap_resource_4(12096) ResourceTask::~ResourceTask: ResourceTask(0x21cc1e0) releasing session KIMAP::Session(0x2446f60)
14:13:19 akonadi_imap_resource_4(12096) ImapResourceBase::doSetOnline: online= true
14:15:14 akonadi_imap_resource_4(12096)/kdepimlibs (kimap) KIMAP::SessionPrivate::responseReceived: Received BYE:  "idle for too long "
14:15:14 akonadi_imap_resource_4(12096)/kdepimlibs (kimap) KIMAP::SessionThread::doCloseSocket: close
14:15:14 akonadi_imap_resource_4(12096)/kdepimlibs (kimap) ImapIdleManager::onIdleStopped: IDLE dropped maybe we should reconnect?
14:15:14 akonadi_imap_resource_4(12096) ImapIdleManager::reconnect: attempting to reconnect IDLE session
14:15:14 akonadi_imap_resource_4(12096) SessionPool::requestSession: pending requests: (2345)
14:15:14 akonadi_imap_resource_4(12096) SessionPool::processPendingRequests: session ready: 2345 KIMAP::Session(0x2446f60)
14:15:14 akonadi_imap_resource_4(12096) ImapIdleManager::onSessionRequestDone: IDLE session is KIMAP::Session(0x2446f60)

At 14:39 I noticed the problem: account gone from kmail's folder tree, so I looked in akonadiconsole and saw only a toplevel item
for this imap resource, with nothing in it. Restarted the resource, it's now fetching everything again from the server, from scratch.

Nothing in the log about why these collections got deleted.

Bug in the imap resource RetrieveCollectionsTask algorithm?

KDE/4.14 branch, from the day before yesterday (April 8).

No problem with the other imap resources; not network related either, I was at the office the whole time.

-- 
David Faure, faure at kde.org, http://www.davidfaure.fr
Working on KDE Frameworks 5

_______________________________________________
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/


More information about the kde-pim mailing list