Create Task
Maniphest T9437

carddavresource sync contacts, and then promptly removes them
Closed, ResolvedPublic
Actions

Description

visible in the UI for the blink of an eye. I'm also curious why the tests didn't catch that.

Trace:   kolabnowCarddav.webdav                   : Job done:  KDAV2::DavCollectionsFetchJob
Trace:   kolabnowCarddav.carddavresource          : Found 2 addressbooks
Log:     kolabnowCarddav.carddavresource          : Found addressbook: "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/" "Contacts"
Trace:   kolabnowCarddav.carddavresource          : Creating addressbook:  "Contacts" ""
Trace:   kolabnowCarddav.synchronizer             : Create or modify "addressbook" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/"
Trace:   kolabnowCarddav.synchronizer             : Starting transaction on sync store.
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationrid.mapping.addressbook" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationlocalid.mapping.addressbook" false
Trace:   kolabnowCarddav.synchronizer             : Found a new entity:  "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Log:     kolabnowCarddav.carddavresource          : Found addressbook: "/addressbooks/test1@kolab.org/{55399646-c2d3-438f-a475-b04d67fadf54}/" "Test AddressBook Collection"
Trace:   kolabnowCarddav.carddavresource          : Creating addressbook:  "Test AddressBook Collection" ""
Trace:   kolabnowCarddav.synchronizer             : Create or modify "addressbook" "/addressbooks/test1@kolab.org/{55399646-c2d3-438f-a475-b04d67fadf54}/"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationrid.mapping.addressbook" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationlocalid.mapping.addressbook" false
Trace:   kolabnowCarddav.synchronizer             : Found a new entity:  "/addressbooks/test1@kolab.org/{55399646-c2d3-438f-a475-b04d67fadf54}/"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Trace:   kolabnowCarddav.webdav                   : Syncing collection: "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationrid.mapping.addressbook" false
Trace:   kolabnowCarddav.webdav                   : Starting job:  KDAV2::DavItemsListJob
Trace:   kolabnowCarddav.webdav                   : Job done:  KDAV2::DavItemsListJob
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Trace:   kolabnowCarddav.webdav                   : Syncing item: "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/1b6c49f0-53bc-471e-8ad3-5a70424db2ad.vcf"
Trace:   kolabnowCarddav.webdav                   : Starting job:  KDAV2::DavItemFetchJob
Trace:   kolabnowCarddav.webdav                   : Job done:  KDAV2::DavItemFetchJob
Trace:   kolabnowCarddav.synchronizer             : Create or modify "contact" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/1b6c49f0-53bc-471e-8ad3-5a70424db2ad.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationrid.mapping.contact" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationlocalid.mapping.contact" false
Trace:   kolabnowCarddav.synchronizer             : Found a new entity:  "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/1b6c49f0-53bc-471e-8ad3-5a70424db2ad.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Log:     kolabnowCarddav.synchronizer             : Progress:  1  out of  5 "" ()
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Trace:   kolabnowCarddav.webdav                   : Syncing item: "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/09affc2c-5d97-49e2-bd44-1af488085ebc.vcf"
Trace:   kolabnowCarddav.webdav                   : Starting job:  KDAV2::DavItemFetchJob
Trace:   kube.resourceaccess                                                                                                       : Received notification:  Notification(Type: "progress" , Id: "" , Code: 0 , Message: "" , Entities: () )
Trace:   kube.notifier.resource                                                                                                    : Received notification in facade:  5
Log:     kube.sinkfabric                                                                                                           : Received notification:  Notification(Type: "progress" , Id: "" , Code: 0 , Message: "" , Entities: () )
Log:     kube.sinkfabric                                                                                                           : Received message:  "progressNotification" QMap(("progress", QVariant(int, 1))("resourceId", QVariant(QByteArray, "kolabnowCarddav"))("total", QVariant(int, 5))("type", QVariant(QString, "progress")))
Trace:   kube.query.folder.folder{4da08a1f-a0d9-475e-9ce1-8c3c9c8cdabf}.kolabnowImap.queryrunner                                   : Stopped query
Trace:   kube.query.account..accounts.resource                                                                                     : Received notification in facade:  5
Trace:   kube.query.account..accounts                                                                                              : Received notification in facade:  5
Trace:   kube.query.account..accounts.resource                                                                                     : Received notification in facade:  5
Trace:   kube.query.account..accounts                                                                                              : Received notification in facade:  5
Trace:   kube.query.contact.resourceQuery.resource                                                                                 : Received notification in facade:  5
Trace:   kolabnowCarddav.webdav                   : Job done:  KDAV2::DavItemFetchJob
Trace:   kolabnowCarddav.synchronizer             : Create or modify "contact" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/09affc2c-5d97-49e2-bd44-1af488085ebc.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationrid.mapping.contact" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationlocalid.mapping.contact" false
Trace:   kolabnowCarddav.synchronizer             : Found a new entity:  "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/09affc2c-5d97-49e2-bd44-1af488085ebc.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Log:     kolabnowCarddav.synchronizer             : Progress:  2  out of  5 "" ()
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Trace:   kolabnowCarddav.webdav                   : Syncing item: "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/515968ce-f392-4d12-b99a-caec30646915.vcf"
Trace:   kolabnowCarddav.webdav                   : Starting job:  KDAV2::DavItemFetchJob
Trace:   kube.resourceaccess                                                                                                       : Received notification:  Notification(Type: "progress" , Id: "" , Code: 0 , Message: "" , Entities: () )
Trace:   kube.notifier.resource                                                                                                    : Received notification in facade:  5
Log:     kube.sinkfabric                                                                                                           : Received notification:  Notification(Type: "progress" , Id: "" , Code: 0 , Message: "" , Entities: () )
Log:     kube.sinkfabric                                                                                                           : Received message:  "progressNotification" QMap(("progress", QVariant(int, 2))("resourceId", QVariant(QByteArray, "kolabnowCarddav"))("total", QVariant(int, 5))("type", QVariant(QString, "progress")))
Trace:   kube.query.account..accounts.resource                                                                                     : Received notification in facade:  5
Trace:   kube.query.account..accounts                                                                                              : Received notification in facade:  5
Trace:   kube.query.account..accounts.resource                                                                                     : Received notification in facade:  5
Trace:   kube.query.account..accounts                                                                                              : Received notification in facade:  5
Trace:   kube.query.contact.resourceQuery.resource                                                                                 : Received notification in facade:  5
Trace:   kolabnowCarddav.webdav                   : Job done:  KDAV2::DavItemFetchJob
Trace:   kolabnowCarddav.synchronizer             : Create or modify "contact" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/515968ce-f392-4d12-b99a-caec30646915.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationrid.mapping.contact" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationlocalid.mapping.contact" false
Trace:   kolabnowCarddav.synchronizer             : Found a new entity:  "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/515968ce-f392-4d12-b99a-caec30646915.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Log:     kolabnowCarddav.synchronizer             : Progress:  3  out of  5 "" ()
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Trace:   kolabnowCarddav.webdav                   : Syncing item: "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/cd97ed09-4142-478f-82fe-98e47472dd9c.vcf"
Trace:   kolabnowCarddav.webdav                   : Starting job:  KDAV2::DavItemFetchJob
Trace:   kube.resourceaccess                                                                                                       : Received notification:  Notification(Type: "progress" , Id: "" , Code: 0 , Message: "" , Entities: () )
Trace:   kube.notifier.resource                                                                                                    : Received notification in facade:  5
Log:     kube.sinkfabric                                                                                                           : Received notification:  Notification(Type: "progress" , Id: "" , Code: 0 , Message: "" , Entities: () )
Log:     kube.sinkfabric                                                                                                           : Received message:  "progressNotification" QMap(("progress", QVariant(int, 3))("resourceId", QVariant(QByteArray, "kolabnowCarddav"))("total", QVariant(int, 5))("type", QVariant(QString, "progress")))
Trace:   kube.query.account..accounts.resource                                                                                     : Received notification in facade:  5
Trace:   kube.query.account..accounts                                                                                              : Received notification in facade:  5
Trace:   kube.query.account..accounts.resource                                                                                     : Received notification in facade:  5
Trace:   kube.query.account..accounts                                                                                              : Received notification in facade:  5
Trace:   kube.query.contact.resourceQuery.resource                                                                                 : Received notification in facade:  5
Trace:   kolabnowCarddav.webdav                   : Job done:  KDAV2::DavItemFetchJob
Trace:   kolabnowCarddav.synchronizer             : Create or modify "contact" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/cd97ed09-4142-478f-82fe-98e47472dd9c.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationrid.mapping.contact" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationlocalid.mapping.contact" false
Trace:   kolabnowCarddav.synchronizer             : Found a new entity:  "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/cd97ed09-4142-478f-82fe-98e47472dd9c.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Log:     kolabnowCarddav.synchronizer             : Progress:  4  out of  5 "" ()
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Trace:   kolabnowCarddav.webdav                   : Syncing item: "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/87ff8ad2-46c1-49d5-88f2-0edc28eaa6f7.vcf"
Trace:   kolabnowCarddav.webdav                   : Starting job:  KDAV2::DavItemFetchJob
Trace:   kube.resourceaccess                                                                                                       : Received notification:  Notification(Type: "progress" , Id: "" , Code: 0 , Message: "" , Entities: () )
Trace:   kube.notifier.resource                                                                                                    : Received notification in facade:  5
Log:     kube.sinkfabric                                                                                                           : Received notification:  Notification(Type: "progress" , Id: "" , Code: 0 , Message: "" , Entities: () )
Log:     kube.sinkfabric                                                                                                           : Received message:  "progressNotification" QMap(("progress", QVariant(int, 4))("resourceId", QVariant(QByteArray, "kolabnowCarddav"))("total", QVariant(int, 5))("type", QVariant(QString, "progress")))
Trace:   kube.query.account..accounts.resource                                                                                     : Received notification in facade:  5
Trace:   kube.query.account..accounts                                                                                              : Received notification in facade:  5
Trace:   kube.query.account..accounts.resource                                                                                     : Received notification in facade:  5
Trace:   kube.query.account..accounts                                                                                              : Received notification in facade:  5
Trace:   kube.query.contact.resourceQuery.resource                                                                                 : Received notification in facade:  5
Trace:   kolabnowCarddav.webdav                   : Job done:  KDAV2::DavItemFetchJob
Trace:   kolabnowCarddav.synchronizer             : Create or modify "contact" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/87ff8ad2-46c1-49d5-88f2-0edc28eaa6f7.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationrid.mapping.contact" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationlocalid.mapping.contact" false
Trace:   kolabnowCarddav.synchronizer             : Found a new entity:  "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/87ff8ad2-46c1-49d5-88f2-0edc28eaa6f7.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Log:     kolabnowCarddav.synchronizer             : Progress:  5  out of  5 "" ()
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore              : Committing transaction
Trace:   kolabnowCarddav.commandprocessor         : Cleanup done. 0 [ms]
Trace:   kolabnowCarddav.pipeline                 : Starting transaction.
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" true
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" true
Trace:   kolabnowCarddav.entitystore              : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor         : Queue processed. 2 [ms]
Trace:   kolabnowCarddav.pipeline                 : Starting transaction.
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "CreateEntity"
Trace:   kolabnowCarddav.pipeline                 : New Entity. Type:  "addressbook" uid:  "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : New entity  ApplicationDomainType(
   Id:           "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}"
   Resource:     "kolabnowCarddav"
   "name"        "Contacts"
   "parent"      QVariant(Invalid)
 )
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddavaddressbookuids" false
Trace:   kolabnowCarddav.entitystore              : Wrote entity:  "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}" "addressbook" 1
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "CreateEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  1 . Processing took:  3 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "CreateEntity"
Trace:   kolabnowCarddav.pipeline                 : New Entity. Type:  "addressbook" uid:  "{5f59ff64-ee0b-46ae-a62b-73e96eda58b3}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : New entity  ApplicationDomainType(
   Id:           "{5f59ff64-ee0b-46ae-a62b-73e96eda58b3}"
   Resource:     "kolabnowCarddav"
   "name"        "Test AddressBook Collection"
   "parent"      QVariant(Invalid)
 )
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddavaddressbookuids" false
Trace:   kolabnowCarddav.entitystore              : Wrote entity:  "{5f59ff64-ee0b-46ae-a62b-73e96eda58b3}" "addressbook" 2
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "CreateEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  2 . Processing took:  1 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "CreateEntity"
Trace:   kolabnowCarddav.pipeline                 : New Entity. Type:  "contact" uid:  "{775f9db4-57b5-4d82-9b15-e938af0da38a}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : New entity  ApplicationDomainType(
   Id:           "{775f9db4-57b5-4d82-9b15-e938af0da38a}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "John"
   "fn"          "John Doe"
   "lastname"    "Doe"
   "photo"       ""
   "uid"         "1b6c49f0-53bc-471e-8ad3-5a70424db2ad"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddavcontactuids" false
Trace:   kolabnowCarddav.entitystore              : Wrote entity:  "{775f9db4-57b5-4d82-9b15-e938af0da38a}" "contact" 3
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "CreateEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  3 . Processing took:  3 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "CreateEntity"
Trace:   kolabnowCarddav.pipeline                 : New Entity. Type:  "contact" uid:  "{e5acf27e-7f6c-4c66-bf0d-35490d680950}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : New entity  ApplicationDomainType(
   Id:           "{e5acf27e-7f6c-4c66-bf0d-35490d680950}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "Kolab"
   "fn"          "Kolab Test"
   "lastname"    "Test"
   "photo"       ""
   "uid"         "09affc2c-5d97-49e2-bd44-1af488085ebc"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddavcontactuids" false
Trace:   kolabnowCarddav.entitystore              : Wrote entity:  "{e5acf27e-7f6c-4c66-bf0d-35490d680950}" "contact" 4
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "CreateEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  4 . Processing took:  2 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "CreateEntity"
Trace:   kolabnowCarddav.pipeline                 : New Entity. Type:  "contact" uid:  "{fd557bef-1382-4f7e-becd-488431c8db04}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : New entity  ApplicationDomainType(
   Id:           "{fd557bef-1382-4f7e-becd-488431c8db04}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "test2"
   "fn"          "test2 2"
   "lastname"    "2"
   "photo"       ""
   "uid"         "515968ce-f392-4d12-b99a-caec30646915"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddavcontactuids" false
Trace:   kolabnowCarddav.entitystore              : Wrote entity:  "{fd557bef-1382-4f7e-becd-488431c8db04}" "contact" 5
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "CreateEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  5 . Processing took:  1 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "CreateEntity"
Trace:   kolabnowCarddav.pipeline                 : New Entity. Type:  "contact" uid:  "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : New entity  ApplicationDomainType(
   Id:           "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "New Mac"
   "fn"          "New Mac Test"
   "lastname"    "Test"
   "photo"       ""
   "uid"         "cd97ed09-4142-478f-82fe-98e47472dd9c"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddavcontactuids" false
Trace:   kolabnowCarddav.entitystore              : Wrote entity:  "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}" "contact" 6
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "CreateEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  6 . Processing took:  1 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "CreateEntity"
Trace:   kolabnowCarddav.pipeline                 : New Entity. Type:  "contact" uid:  "{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : New entity  ApplicationDomainType(
   Id:           "{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "New"
   "fn"          "New Contact"
   "lastname"    "Contact"
   "photo"       ""
   "uid"         "87ff8ad2-46c1-49d5-88f2-0edc28eaa6f7"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddavcontactuids" false
Trace:   kolabnowCarddav.entitystore              : Wrote entity:  "{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}" "contact" 7
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "CreateEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  7 . Processing took:  1 [ms]
Trace:   kolabnowCarddav.pipeline                 : Committing revision:  7 : 7  items in:  65 [ms]   9.28571 [ms/item]
Trace:   kolabnowCarddav.entitystore              : Committing transaction
Trace:   kolabnowCarddav.listener                 : Sending revision update for  "PID: 88 ResourceAccess: 10290448" 7
Trace:   kolabnowCarddav.commandprocessor         : Queue processed. 115 [ms]
Trace:   kube.resourceaccess                                                                                                       : Received notification:  Notification(Type: "progress" , Id: "" , Code: 0 , Message: "" , Entities: () )
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" true
Trace:   kube.notifier.resource                                                                                                    : Received notification in facade:  5
Log:     kube.sinkfabric                                                                                                           : Received notification:  Notification(Type: "progress" , Id: "" , Code: 0 , Message: "" , Entities: () )
Log:     kube.sinkfabric                                                                                                           : Received message:  "progressNotification" QMap(("progress", QVariant(int, 5))("resourceId", QVariant(QByteArray, "kolabnowCarddav"))("total", QVariant(int, 5))("type", QVariant(QString, "progress")))
Trace:   kube.query.account..accounts.resource                                                                                     : Received notification in facade:  5
Trace:   kube.query.account..accounts                                                                                              : Received notification in facade:  5
Trace:   kube.query.account..accounts.resource                                                                                     : Received notification in facade:  5
Trace:   kube.query.account..accounts                                                                                              : Received notification in facade:  5
Trace:   kube.query.contact.resourceQuery.resource                                                                                 : Received notification in facade:  5
Trace:   kube.resourceaccess                                                                                                       : "Revision updated to: 7"
Trace:   kube.notifier.resource                                                                                                    : Received notification in facade:  7
Log:     kube.sinkfabric                                                                                                           : Received notification:  Notification(Type: "revisionupdate" , Id: "" , Code: 0 , Message: "" , Entities: () )
Trace:   kube.query.account..accounts.resource                                                                                     : Received notification in facade:  7
Trace:   kube.query.account..accounts                                                                                              : Received notification in facade:  7
Trace:   kube.query.account..accounts.resource                                                                                     : Received notification in facade:  7
Trace:   kube.query.account..accounts                                                                                              : Received notification in facade:  7
Trace:   kube.query.contact.resourceQuery.resource                                                                                 : Received notification in facade:  7
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Starting query worker
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Running query update from revision:  1  to revision  7
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Executing query update from revision  1  to revision  7
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Incremental changes:  QVector("{775f9db4-57b5-4d82-9b15-e938af0da38a}0000000000000000003", "{e5acf27e-7f6c-4c66-bf0d-35490d680950}0000000000000000004", "{fd557bef-1382-4f7e-becd-488431c8db04}0000000000000000005", "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}0000000000000000006", "{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}0000000000000000007")
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Filtered set retrieved.  0 [ms]
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Source: Read entity:  "{775f9db4-57b5-4d82-9b15-e938af0da38a}" "Creation"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Got incremental result:  "{775f9db4-57b5-4d82-9b15-e938af0da38a}" "Creation"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Replaying:  "Creation"
 ApplicationDomainType(
   Id:           "{775f9db4-57b5-4d82-9b15-e938af0da38a}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "John"
   "fn"          "John Doe"
   "lastname"    "Doe"
   "photo"       ""
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kube.query.contact.modelresult                                                                                            : Received addition:  "{775f9db4-57b5-4d82-9b15-e938af0da38a}"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Source: Read entity:  "{e5acf27e-7f6c-4c66-bf0d-35490d680950}" "Creation"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Got incremental result:  "{e5acf27e-7f6c-4c66-bf0d-35490d680950}" "Creation"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Replaying:  "Creation"
 ApplicationDomainType(
   Id:           "{e5acf27e-7f6c-4c66-bf0d-35490d680950}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "Kolab"
   "fn"          "Kolab Test"
   "lastname"    "Test"
   "photo"       ""
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kube.query.contact.modelresult                                                                                            : Received addition:  "{e5acf27e-7f6c-4c66-bf0d-35490d680950}"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Source: Read entity:  "{fd557bef-1382-4f7e-becd-488431c8db04}" "Creation"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Got incremental result:  "{fd557bef-1382-4f7e-becd-488431c8db04}" "Creation"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Replaying:  "Creation"
 ApplicationDomainType(
   Id:           "{fd557bef-1382-4f7e-becd-488431c8db04}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "test2"
   "fn"          "test2 2"
   "lastname"    "2"
   "photo"       ""
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kube.query.contact.modelresult                                                                                            : Received addition:  "{fd557bef-1382-4f7e-becd-488431c8db04}"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Source: Read entity:  "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}" "Creation"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Got incremental result:  "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}" "Creation"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Replaying:  "Creation"
 ApplicationDomainType(
   Id:           "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "New Mac"
   "fn"          "New Mac Test"
   "lastname"    "Test"
   "photo"       ""
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kube.query.contact.modelresult                                                                                            : Received addition:  "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Source: Read entity:  "{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}" "Creation"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Got incremental result:  "{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}" "Creation"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Replaying:  "Creation"
 ApplicationDomainType(
   Id:           "{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "New"
   "fn"          "New Contact"
   "lastname"    "Contact"
   "photo"       ""
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kube.query.contact.modelresult                                                                                            : Received addition:  "{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Replayed  5  results until revision:  7
 Replayed all available results.
 Incremental query took:  5 [ms]
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Stopped query worker
Trace:   kolabnowCarddav.synchronizer             : Starting transaction on sync store.
Trace:   kolabnowCarddav.storage_lmdb             : Found dbi that is not available for the current transaction.
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/cd97ed09-4142-478f-82fe-98e47472dd9c.vcf"
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{775f9db4-57b5-4d82-9b15-e938af0da38a}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/1b6c49f0-53bc-471e-8ad3-5a70424db2ad.vcf"
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/87ff8ad2-46c1-49d5-88f2-0edc28eaa6f7.vcf"
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{e5acf27e-7f6c-4c66-bf0d-35490d680950}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/09affc2c-5d97-49e2-bd44-1af488085ebc.vcf"
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{fd557bef-1382-4f7e-becd-488431c8db04}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/515968ce-f392-4d12-b99a-caec30646915.vcf"
Trace:   kolabnowCarddav.webdav                   : Syncing collection: "/addressbooks/test1@kolab.org/{55399646-c2d3-438f-a475-b04d67fadf54}/"
Trace:   kolabnowCarddav.webdav                   : Starting job:  KDAV2::DavItemsListJob
Trace:   kolabnowCarddav.synchronizer             : Sync still in progress.
Trace:   kolabnowCarddav.webdav                   : Job done:  KDAV2::DavItemsListJob
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/cd97ed09-4142-478f-82fe-98e47472dd9c.vcf"
Trace:   kolabnowCarddav.synchronizer             : Found a removed entity:  "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}"
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{775f9db4-57b5-4d82-9b15-e938af0da38a}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/1b6c49f0-53bc-471e-8ad3-5a70424db2ad.vcf"
Trace:   kolabnowCarddav.synchronizer             : Found a removed entity:  "{775f9db4-57b5-4d82-9b15-e938af0da38a}"
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/87ff8ad2-46c1-49d5-88f2-0edc28eaa6f7.vcf"
Trace:   kolabnowCarddav.synchronizer             : Found a removed entity:  "{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}"
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{e5acf27e-7f6c-4c66-bf0d-35490d680950}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/09affc2c-5d97-49e2-bd44-1af488085ebc.vcf"
Trace:   kolabnowCarddav.synchronizer             : Found a removed entity:  "{e5acf27e-7f6c-4c66-bf0d-35490d680950}"
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{fd557bef-1382-4f7e-becd-488431c8db04}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/515968ce-f392-4d12-b99a-caec30646915.vcf"
Trace:   kolabnowCarddav.synchronizer             : Found a removed entity:  "{fd557bef-1382-4f7e-becd-488431c8db04}"
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{5f59ff64-ee0b-46ae-a62b-73e96eda58b3}" "/addressbooks/test1@kolab.org/{55399646-c2d3-438f-a475-b04d67fadf54}/"
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/"
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore              : Committing transaction
Trace:   kolabnowCarddav.commandprocessor         : Cleanup done. 1 [ms]
Trace:   kolabnowCarddav.pipeline                 : Starting transaction.
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" true
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" true
Trace:   kolabnowCarddav.entitystore              : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor         : Queue processed. 1 [ms]
Trace:   kolabnowCarddav.pipeline                 : Starting transaction.
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "DeleteEntity"
Trace:   kolabnowCarddav.pipeline                 : Deleted Entity. Type:  "contact" uid:  "{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : Removed entity  ApplicationDomainType(
   Id:           "{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "New"
   "fn"          "New Contact"
   "lastname"    "Contact"
   "photo"       ""
   "uid"         "87ff8ad2-46c1-49d5-88f2-0edc28eaa6f7"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "DeleteEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  8 . Processing took:  1 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "DeleteEntity"
Trace:   kolabnowCarddav.pipeline                 : Deleted Entity. Type:  "contact" uid:  "{e5acf27e-7f6c-4c66-bf0d-35490d680950}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : Removed entity  ApplicationDomainType(
   Id:           "{e5acf27e-7f6c-4c66-bf0d-35490d680950}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "Kolab"
   "fn"          "Kolab Test"
   "lastname"    "Test"
   "photo"       ""
   "uid"         "09affc2c-5d97-49e2-bd44-1af488085ebc"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "DeleteEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  9 . Processing took:  1 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "DeleteEntity"
Trace:   kolabnowCarddav.pipeline                 : Deleted Entity. Type:  "contact" uid:  "{fd557bef-1382-4f7e-becd-488431c8db04}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : Removed entity  ApplicationDomainType(
   Id:           "{fd557bef-1382-4f7e-becd-488431c8db04}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "test2"
   "fn"          "test2 2"
   "lastname"    "2"
   "photo"       ""
   "uid"         "515968ce-f392-4d12-b99a-caec30646915"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "DeleteEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  10 . Processing took:  1 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "DeleteEntity"
Trace:   kolabnowCarddav.pipeline                 : Deleted Entity. Type:  "contact" uid:  "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : Removed entity  ApplicationDomainType(
   Id:           "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "New Mac"
   "fn"          "New Mac Test"
   "lastname"    "Test"
   "photo"       ""
   "uid"         "cd97ed09-4142-478f-82fe-98e47472dd9c"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "DeleteEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  11 . Processing took:  2 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "DeleteEntity"
Trace:   kolabnowCarddav.pipeline                 : Deleted Entity. Type:  "contact" uid:  "{775f9db4-57b5-4d82-9b15-e938af0da38a}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : Removed entity  ApplicationDomainType(
   Id:           "{775f9db4-57b5-4d82-9b15-e938af0da38a}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "John"
   "fn"          "John Doe"
   "lastname"    "Doe"
   "photo"       ""
   "uid"         "1b6c49f0-53bc-471e-8ad3-5a70424db2ad"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "DeleteEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  12 . Processing took:  1 [ms]
Trace:   kolabnowCarddav.pipeline                 : Committing revision:  12 : 5  items in:  65 [ms]   13 [ms/item]
Trace:   kolabnowCarddav.entitystore              : Committing transaction
Trace:   kolabnowCarddav.listener                 : Sending revision update for  "PID: 88 ResourceAccess: 10290448" 12
Trace:   kolabnowCarddav.commandprocessor         : Queue processed. 115 [ms]
Trace:   kube.resourceaccess                                                                                                       : "Revision updated to: 12"
Trace:   kube.notifier.resource                                                                                                    : Received notification in facade:  7
Log:     kube.sinkfabric                                                                                                           : Received notification:  Notification(Type: "revisionupdate" , Id: "" , Code: 0 , Message: "" , Entities: () )
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" true
Trace:   kube.query.account..accounts.resource                                                                                     : Received notification in facade:  7
Trace:   kube.query.account..accounts                                                                                              : Received notification in facade:  7
Trace:   kube.query.account..accounts.resource                                                                                     : Received notification in facade:  7
Trace:   kube.query.account..accounts                                                                                              : Received notification in facade:  7
Trace:   kube.query.contact.resourceQuery.resource                                                                                 : Received notification in facade:  7
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Starting query worker
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Running query update from revision:  8  to revision  12
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Executing query update from revision  8  to revision  12
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Incremental changes:  QVector("{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}0000000000000000008", "{e5acf27e-7f6c-4c66-bf0d-35490d680950}0000000000000000009", "{fd557bef-1382-4f7e-becd-488431c8db04}0000000000000000010", "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}0000000000000000011", "{775f9db4-57b5-4d82-9b15-e938af0da38a}0000000000000000012")
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Filtered set retrieved.  1 [ms]
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Source: Read entity:  "{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}" "Removal"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Got incremental result:  "{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}" "Removal"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Replaying:  "Removal"
 ApplicationDomainType(
   Id:           "{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}"
   Resource:     "kolabnowCarddav"
   "addressbook"         QVariant(Invalid)
   "emails"      QVariant(Invalid)
   "firstname"   QVariant(Invalid)
   "fn"          QVariant(Invalid)
   "lastname"    QVariant(Invalid)
   "photo"       QVariant(Invalid)
   "vcard"       QVariant(Invalid)
 )
Trace:   kube.query.contact.modelresult                                                                                            : Received removal:  "{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}"
Trace:   kube.query.contact.modelresult                                                                                            : Removed entity 1767390333
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Source: Read entity:  "{e5acf27e-7f6c-4c66-bf0d-35490d680950}" "Removal"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Got incremental result:  "{e5acf27e-7f6c-4c66-bf0d-35490d680950}" "Removal"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Replaying:  "Removal"
 ApplicationDomainType(
   Id:           "{e5acf27e-7f6c-4c66-bf0d-35490d680950}"
   Resource:     "kolabnowCarddav"
   "addressbook"         QVariant(Invalid)
   "emails"      QVariant(Invalid)
   "firstname"   QVariant(Invalid)
   "fn"          QVariant(Invalid)
   "lastname"    QVariant(Invalid)
   "photo"       QVariant(Invalid)
   "vcard"       QVariant(Invalid)
 )
Trace:   kube.query.contact.modelresult                                                                                            : Received removal:  "{e5acf27e-7f6c-4c66-bf0d-35490d680950}"
Trace:   kube.query.contact.modelresult                                                                                            : Removed entity 3949575990
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Source: Read entity:  "{fd557bef-1382-4f7e-becd-488431c8db04}" "Removal"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Got incremental result:  "{fd557bef-1382-4f7e-becd-488431c8db04}" "Removal"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Replaying:  "Removal"
 ApplicationDomainType(
   Id:           "{fd557bef-1382-4f7e-becd-488431c8db04}"
   Resource:     "kolabnowCarddav"
   "addressbook"         QVariant(Invalid)
   "emails"      QVariant(Invalid)
   "firstname"   QVariant(Invalid)
   "fn"          QVariant(Invalid)
   "lastname"    QVariant(Invalid)
   "photo"       QVariant(Invalid)
   "vcard"       QVariant(Invalid)
 )
Trace:   kube.query.contact.modelresult                                                                                            : Received removal:  "{fd557bef-1382-4f7e-becd-488431c8db04}"
Trace:   kube.query.contact.modelresult                                                                                            : Removed entity 963231007
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Source: Read entity:  "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}" "Removal"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Got incremental result:  "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}" "Removal"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Replaying:  "Removal"
 ApplicationDomainType(
   Id:           "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}"
   Resource:     "kolabnowCarddav"
   "addressbook"         QVariant(Invalid)
   "emails"      QVariant(Invalid)
   "firstname"   QVariant(Invalid)
   "fn"          QVariant(Invalid)
   "lastname"    QVariant(Invalid)
   "photo"       QVariant(Invalid)
   "vcard"       QVariant(Invalid)
 )
Trace:   kube.query.contact.modelresult                                                                                            : Received removal:  "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}"
Trace:   kube.query.contact.modelresult                                                                                            : Removed entity 1767667466
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Source: Read entity:  "{775f9db4-57b5-4d82-9b15-e938af0da38a}" "Removal"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker.entitystore.datastorequery                                          : Got incremental result:  "{775f9db4-57b5-4d82-9b15-e938af0da38a}" "Removal"
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Replaying:  "Removal"
 ApplicationDomainType(
   Id:           "{775f9db4-57b5-4d82-9b15-e938af0da38a}"
   Resource:     "kolabnowCarddav"
   "addressbook"         QVariant(Invalid)
   "emails"      QVariant(Invalid)
   "firstname"   QVariant(Invalid)
   "fn"          QVariant(Invalid)
   "lastname"    QVariant(Invalid)
   "photo"       QVariant(Invalid)
   "vcard"       QVariant(Invalid)
 )
Trace:   kube.query.contact.modelresult                                                                                            : Received removal:  "{775f9db4-57b5-4d82-9b15-e938af0da38a}"
Trace:   kube.query.contact.modelresult                                                                                            : Removed entity 1836320027
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Replayed  5  results until revision:  12
 Replayed all available results.
 Incremental query took:  4 [ms]
Trace:   kube.query.contact.kolabnowCarddav.queryrunner.worker                                                                     : Stopped query worker
Log:     kolabnowCarddav.synchronizer             : Done Synchronizing
Trace:   kolabnowCarddav.synchronizer             : Sync request processed
Trace:   kolabnowCarddav.synchronizer.entitystore : Aborting transaction
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore              : Committing transaction
Trace:   kolabnowCarddav.commandprocessor         : Cleanup done. 0 [ms]
Trace:   kolabnowCarddav.pipeline                 : Starting transaction.
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" true
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" true
Trace:   kolabnowCarddav.entitystore              : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor         : Queue processed. 1 [ms]
Trace:   kolabnowCarddav.pipeline                 : Starting transaction.
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore              : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor         : Queue processed. 1 [ms]
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" true
Trace:   kolabnowCarddav.synchronizer             : Queue is not empty
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.changereplaydefault" true
Trace:   kolabnowCarddav.synchronizer.entitystore : Starting transaction:  0
Log:     kolabnowCarddav.synchronizer             : Replaying changes.
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.changereplaydefault" true
Trace:   kolabnowCarddav.synchronizer.changereplay : Changereplay from  0  to  12
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{e8aca9df-b3cb-4440-af60-1ad34afb20fd}0000000000000000001"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{5f59ff64-ee0b-46ae-a62b-73e96eda58b3}0000000000000000002"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{775f9db4-57b5-4d82-9b15-e938af0da38a}0000000000000000003"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{e5acf27e-7f6c-4c66-bf0d-35490d680950}0000000000000000004"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{fd557bef-1382-4f7e-becd-488431c8db04}0000000000000000005"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}0000000000000000006"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}0000000000000000007"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{8ed6aafb-77fb-4d6b-91c2-407ce0c1d297}0000000000000000008"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{e5acf27e-7f6c-4c66-bf0d-35490d680950}0000000000000000009"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{fd557bef-1382-4f7e-becd-488431c8db04}0000000000000000010"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{69e9e54e-7b47-46ba-b14e-a833f8df64cc}0000000000000000011"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{775f9db4-57b5-4d82-9b15-e938af0da38a}0000000000000000012"
Trace:   kolabnowCarddav.synchronizer.changereplay : Replayed until:  12
Trace:   kolabnowCarddav.storage_lmdb              : Creating database dynamically:  "kolabnowCarddav.changereplaydefault" false
Log:     kolabnowCarddav.synchronizer              : Progress:  12  out of  12 "changereplay" ()
Trace:   kolabnowCarddav.synchronizer.changereplay : Change replay complete.
Trace:   kolabnowCarddav.synchronizer.changereplay : All changes replayed
Log:     kolabnowCarddav.synchronizer              : Done replaying changes
Trace:   kolabnowCarddav.synchronizer              : Sync request processed
Trace:   kolabnowCarddav.synchronizer.entitystore  : Aborting transaction
Trace:   kolabnowCarddav.entitystore               : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore               : Committing transaction
Trace:   kolabnowCarddav.commandprocessor          : Cleanup done. 0 [ms]
Trace:   kolabnowCarddav.pipeline                  : Starting transaction.
Trace:   kolabnowCarddav.entitystore               : Starting transaction:  1
Trace:   kolabnowCarddav.storage_lmdb              : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" true
Trace:   kolabnowCarddav.storage_lmdb              : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" true
Trace:   kolabnowCarddav.entitystore               : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor          : Queue processed. 1 [ms]
Trace:   kolabnowCarddav.pipeline                  : Starting transaction.
Trace:   kolabnowCarddav.entitystore               : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore               : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor          : Queue processed. 0 [ms]
Trace:   kolabnowCarddav.storage_lmdb              : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" true
Log:     kolabnowCarddav.synchronizer              : All requests processed.
Trace:   kolabnowCarddav.synchronizer.entitystore  : Starting transaction:  0
Trace:   kolabnowCarddav.synchronizer              : Sync request processed
Trace:   kolabnowCarddav.synchronizer.entitystore  : Aborting transaction
Trace:   kolabnowCarddav.entitystore               : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore               : Committing transaction
Trace:   kolabnowCarddav.commandprocessor          : Cleanup done. 0 [ms]
Trace:   kolabnowCarddav.pipeline                  : Starting transaction.
Trace:   kolabnowCarddav.entitystore               : Starting transaction:  1
Trace:   kolabnowCarddav.storage_lmdb              : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" true
Trace:   kolabnowCarddav.storage_lmdb              : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" true
Trace:   kolabnowCarddav.entitystore               : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor          : Queue processed. 1 [ms]
Trace:   kolabnowCarddav.pipeline                  : Starting transaction.
Trace:   kolabnowCarddav.entitystore               : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore               : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor          : Queue processed. 1 [ms]
Trace:   kolabnowCarddav.storage_lmdb              : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" true
Log:     kolabnowCarddav.synchronizer              : All requests processed.
cmollekopf created this task.Aug 17 2018, 10:38 AM
cmollekopf triaged this task as Normal priority.
cmollekopf added a comment.Aug 17 2018, 1:33 PM
bash-4.4$ sinksh sync kolabnowCarddav
Log:     sinksh.store              : Synchronizing all resource matching:  Query [""] << Id: ""
  Filter: QHash()
  Ids: ("kolabnowCarddav")
  Sorting: ""
  Requested: ()
  Parent: ""
  IsLive: false
  ResourceFilter: Filter(QHash())

Trace:   sinksh.query.resource     : Query:  Query ["resource"] << Id: ""
  Filter: QHash()
  Ids: ("kolabnowCarddav")
  Sorting: ""
  Requested: ()
  Parent: ""
  IsLive: false
  ResourceFilter: Filter(QHash())

Trace:   sinksh.query.resource.    : Trying to fetch from resource  ""
Trace:   sinksh.query.resource.modelresult : Setting fetcher
Trace:   sinksh.query.resource.modelresult : Fetching more:  QModelIndex(-1,-1,0x0,QObject(0x0))
Trace:   sinksh.query.resource.modelresult : Fetching more.
Trace:   sinksh.query.resource..resource.config : Found match  "kolabnowCarddav"
Trace:   sinksh.resourceaccess                  : Starting access
Trace:   sinksh.query.resource.modelresult      : Received addition:  "kolabnowCarddav"
Trace:   sinksh.query.resource.modelresult      : Initial result set complete. Fetched all:  true
Trace:   sinksh.resourcecontrol                 : flushMessageQueue ("kolabnowCarddav")
Log:     sinksh.store                           : Synchronizing  "kolabnowCarddav" Query [""] << Id: ""
  Filter: QHash()
  Ids: ()
  Sorting: ""

Trace:   sinksh.kolabnowCarddav.resourceaccess  : Trying to connect
Trace:   sinksh.kolabnowCarddav.resourceaccess  : Failed to connect, starting resource
Log:     sinksh.kolabnowCarddav.resourceaccess  : Starting resource  "/install/bin/sink_synchronizer" "kolabnowCarddav sink.carddav" Home path:  "/home/developer"
Trace:   sinksh.kolabnowCarddav.resourceaccess  : Started resource  66
Trace:   sinksh.state                           : Command started
Log:     kolabnowCarddav.main      : Starting:  "kolabnowCarddav" "sink.carddav"
Trace:   kolabnowCarddav.listener  : Trying to open  "kolabnowCarddav"
Trace:   kolabnowCarddav.listener  : "Listening on kolabnowCarddav"
Trace:   sinksh.resourceaccess                  : Socket is initialized. 99 [ms]
Trace:   sinksh.resourceaccess                  : "Connected: /tmp/kolabnowCarddav"
Trace:   sinksh.resourceaccess                  : We have  0  pending commands
Trace:   sinksh.resourceaccess                  : We have  1  queued commands
Trace:   sinksh.resourceaccess                  : Pending commands:  0
Trace:   sinksh.resourceaccess                  : "Sending command \"Synchronize\" with messageId 2"
Trace:   sinksh.resourceaccess                  : We have  1  queued commands
Trace:   sinksh.resourceaccess                  : Pending commands:  1
Trace:   kolabnowCarddav.entitystore : Starting transaction:  1
Trace:   sinksh.resourceaccess                  : "Sending command \"Secret\" with messageId 3"
Log:     kolabnowCarddav.entitystore : Creating resource database.
Trace:   kolabnowCarddav.entitystore : Committing transaction
Trace:   kolabnowCarddav.synchronizer : Starting synchronizer:  "sink.carddav" "kolabnowCarddav"
Trace:   kolabnowCarddav.storage_lmdb : Creating database dynamically:  "kolabnowCarddav.changereplaydefault" true
Trace:   kolabnowCarddav.listener     : "Resource factory: 24913008"
Trace:   kolabnowCarddav.listener     : "\tResource: 25564512"
Log:     kolabnowCarddav.synchronizer : Secret not available but required.
Trace:   kolabnowCarddav.listener     : Accepting connection
Trace:   kolabnowCarddav.storage_lmdb : Creating database dynamically:  "kolabnowCarddav.changereplaydefault" true
Trace:   kolabnowCarddav.listener     : Reading from socket...
Trace:   kolabnowCarddav.listener     : Received message. Id: 1  CommandId:  2  Size:  60
Trace:   kolabnowCarddav.listener     : "Completed command messageid 1 of type \"Handshake\" from Unknown Client"
Trace:   kolabnowCarddav.listener     : Received message. Id: 2  CommandId:  4  Size:  44
Trace:   kolabnowCarddav.listener     : Command id   2  of type " "Synchronize" " from  "PID: 64 ResourceAccess: 32049232"
Trace:   kolabnowCarddav.synchronizer : Synchronizing
Log:     kolabnowCarddav.synchronizer : Secret not available but required.
Trace:   sinksh.resourceaccess                  : "Command with messageId 1 completed sucessfully"
Trace:   kolabnowCarddav.listener     : "Completed command messageid 2 of type \"Synchronize\" from PID: 64 ResourceAccess: 32049232"
Trace:   kolabnowCarddav.listener     : Received message. Id: 3  CommandId:  16  Size:  48
Trace:   kolabnowCarddav.storage_lmdb : Creating database dynamically:  "kolabnowCarddav.changereplaydefault" true
Trace:   sinksh.resourceaccess                  : Received notification:  Notification(Type: "warning" , Id: "" , Code: 2 , Message: "Secret is not available." , Entities: () )
Trace:   sinksh.query.resource..resource        : Received notification in facade:  3
Trace:   kolabnowCarddav.synchronizer.entitystore : Starting transaction:  0
Trace:   sinksh.resourceaccess                  : "Command with messageId 2 completed sucessfully"
Trace:   kolabnowCarddav.synchronizer             : Sync request processed
Trace:   kolabnowCarddav.synchronizer.entitystore : Aborting transaction
Trace:   sinksh.resourceaccess                  : Command complete  2
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   sinksh.store                           : Synchronization of resource  "kolabnowCarddav"  complete.
Trace:   kolabnowCarddav.entitystore              : Committing transaction
Trace:   kolabnowCarddav.commandprocessor         : Cleanup done. 1 [ms]
Trace:   kolabnowCarddav.pipeline                 : Starting transaction.
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" true
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" true
Trace:   sinksh.resourcecontrol                 : Waiting for flush completion notification  "{e62505a9-57e5-4a8f-9493-14af999c3002}"
Trace:   kolabnowCarddav.entitystore              : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor         : Queue processed. 1 [ms]
Trace:   sinksh.resourceaccess                  : We have  1  queued commands
Trace:   kolabnowCarddav.pipeline                 : Starting transaction.
Trace:   sinksh.resourceaccess                  : Pending commands:  1
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   sinksh.resourceaccess                  : "Sending command \"Flush\" with messageId 4"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" true
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" true
Trace:   kolabnowCarddav.entitystore              : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor         : Queue processed. 0 [ms]
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" true
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" true
Trace:   kolabnowCarddav.synchronizer             : Queue is not empty
Log:     kolabnowCarddav.webdav                   : Synchronizing "contact" through WebDAV at: QUrl("https://test1%40kolab.org@apps.kolabnow.com")
Trace:   kolabnowCarddav.synchronizer.entitystore : Starting transaction:  0
Log:     kolabnowCarddav.synchronizer             : Synchronizing:  Query ["contact"] << Id: ""
  Filter: QHash()
  Ids: ()
  Sorting: ""

Trace:   kolabnowCarddav.webdav                   : Starting job:  KDAV2::DavCollectionsFetchJob
Trace:   kolabnowCarddav.listener                 : "Completed command messageid 3 of type \"Secret\" from PID: 64 ResourceAccess: 32049232"
Trace:   kolabnowCarddav.listener                 : Reading from socket...
Trace:   kolabnowCarddav.listener                 : Received message. Id: 4  CommandId:  15  Size:  68
Trace:   kolabnowCarddav.listener                 : Command id   4  of type " "Flush" " from  "PID: 64 ResourceAccess: 32049232"
Trace:   sinksh.resourceaccess                  : Updated status:  3
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" false
Trace:   sinksh.resourceaccess                  : Received notification:  Notification(Type: "status" , Id: "" , Code: 3 , Message: "Synchronization has started." , Entities: () )
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.userqueuedefault" false
Trace:   sinksh.query.resource..resource        : Received notification in facade:  1
Trace:   sinksh.query.resource..resource.config : Status changed  "kolabnowCarddav"
Trace:   sinksh.query.resource.modelresult      : Received modification:  "kolabnowCarddav"
Trace:   sinksh.query.resource.modelresult      : Modified entity: "kolabnowCarddav" , id:  371607868
Trace:   sinksh.resourcecontrol                 : Received notification:  1 ""
Trace:   sinksh.resourceaccess                  : Received notification:  Notification(Type: "info" , Id: "" , Code: 1 , Message: "" , Entities: () )
Trace:   sinksh.query.resource..resource        : Received notification in facade:  2
Trace:   sinksh.resourcecontrol                 : Received notification:  2 ""
Trace:   sinksh.resourceaccess                  : "Command with messageId 3 completed sucessfully"
Trace:   sinksh.resourceaccess                  : Command complete  3
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore              : Committing transaction
Trace:   kolabnowCarddav.commandprocessor         : Cleanup done. 0 [ms]
Trace:   kolabnowCarddav.pipeline                 : Starting transaction.
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "Flush"
Trace:   kolabnowCarddav.commandprocessor         : Emitting flush completion "{e62505a9-57e5-4a8f-9493-14af999c3002}"
Trace:   kolabnowCarddav.synchronizer             : Flush complete:  "{e62505a9-57e5-4a8f-9493-14af999c3002}"
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "Flush"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  -1 . Processing took:  1 [ms]
Trace:   kolabnowCarddav.entitystore              : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor         : Queue processed. 22 [ms]
Trace:   kolabnowCarddav.pipeline                 : Starting transaction.
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" true
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" true
Trace:   kolabnowCarddav.entitystore              : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor         : Queue processed. 1 [ms]
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" true
Trace:   kolabnowCarddav.listener                 : "Completed command messageid 4 of type \"Flush\" from PID: 64 ResourceAccess: 32049232"
Trace:   sinksh.resourceaccess                  : Received notification:  Notification(Type: "flushcompletion" , Id: "{e62505a9-57e5-4a8f-9493-14af999c3002}" , Code: 0 , Message: "" , Entities: () )
Trace:   sinksh.query.resource..resource        : Received notification in facade:  8
Trace:   sinksh.resourcecontrol                 : Received notification:  8 "{e62505a9-57e5-4a8f-9493-14af999c3002}"
Trace:   sinksh.resourcecontrol                 : FlushComplete
Trace:   sinksh.resourcecontrol                 : Waiting for flush completion notification  "{91659d75-0d1f-4a56-ab2a-196b4a2ff39b}"
Trace:   sinksh.resourceaccess                  : We have  1  queued commands
Trace:   sinksh.resourceaccess                  : Pending commands:  1
Trace:   sinksh.resourceaccess                  : "Sending command \"Flush\" with messageId 5"
Trace:   sinksh.resourcecontrol                 : Received notification:  8 "{e62505a9-57e5-4a8f-9493-14af999c3002}"
Trace:   sinksh.resourceaccess                  : "Command with messageId 4 completed sucessfully"
Trace:   sinksh.resourceaccess                  : Command complete  4
Trace:   kolabnowCarddav.listener                 : Reading from socket...
Trace:   kolabnowCarddav.listener                 : Received message. Id: 5  CommandId:  15  Size:  68
Trace:   kolabnowCarddav.listener                 : Command id   5  of type " "Flush" " from  "PID: 64 ResourceAccess: 32049232"
Trace:   kolabnowCarddav.synchronizer             : Flushing the synchronization queue  "{91659d75-0d1f-4a56-ab2a-196b4a2ff39b}"
Trace:   kolabnowCarddav.synchronizer             : Sync still in progress.
Trace:   kolabnowCarddav.listener                 : "Completed command messageid 5 of type \"Flush\" from PID: 64 ResourceAccess: 32049232"
Trace:   sinksh.resourceaccess                  : "Command with messageId 5 completed sucessfully"
Trace:   sinksh.resourceaccess                  : Command complete  5
Trace:   kolabnowCarddav.webdav                   : Job done:  KDAV2::DavCollectionsFetchJob
Trace:   kolabnowCarddav.carddavresource          : Found 2 addressbooks
Log:     kolabnowCarddav.carddavresource          : Found addressbook: "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/" "Contacts"
Trace:   kolabnowCarddav.carddavresource          : Creating addressbook:  "Contacts" ""
Trace:   kolabnowCarddav.synchronizer             : Create or modify "addressbook" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/"
Trace:   kolabnowCarddav.synchronizer             : Starting transaction on sync store.
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationrid.mapping.addressbook" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationlocalid.mapping.addressbook" false
Trace:   kolabnowCarddav.synchronizer             : Found a new entity:  "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Log:     kolabnowCarddav.carddavresource          : Found addressbook: "/addressbooks/test1@kolab.org/{55399646-c2d3-438f-a475-b04d67fadf54}/" "Test AddressBook Collection"
Trace:   kolabnowCarddav.carddavresource          : Creating addressbook:  "Test AddressBook Collection" ""
Trace:   kolabnowCarddav.synchronizer             : Create or modify "addressbook" "/addressbooks/test1@kolab.org/{55399646-c2d3-438f-a475-b04d67fadf54}/"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationrid.mapping.addressbook" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationlocalid.mapping.addressbook" false
Trace:   kolabnowCarddav.synchronizer             : Found a new entity:  "/addressbooks/test1@kolab.org/{55399646-c2d3-438f-a475-b04d67fadf54}/"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Trace:   kolabnowCarddav.webdav                   : Syncing collection: "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationrid.mapping.addressbook" false
Trace:   kolabnowCarddav.webdav                   : Starting job:  KDAV2::DavItemsListJob
Trace:   kolabnowCarddav.webdav                   : Job done:  KDAV2::DavItemsListJob
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Trace:   kolabnowCarddav.webdav                   : Syncing item: "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/1b6c49f0-53bc-471e-8ad3-5a70424db2ad.vcf"
Trace:   kolabnowCarddav.webdav                   : Starting job:  KDAV2::DavItemFetchJob
Trace:   kolabnowCarddav.webdav                   : Job done:  KDAV2::DavItemFetchJob
Trace:   kolabnowCarddav.synchronizer             : Create or modify "contact" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/1b6c49f0-53bc-471e-8ad3-5a70424db2ad.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationrid.mapping.contact" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationlocalid.mapping.contact" false
Trace:   kolabnowCarddav.synchronizer             : Found a new entity:  "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/1b6c49f0-53bc-471e-8ad3-5a70424db2ad.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Log:     kolabnowCarddav.synchronizer             : Progress:  1  out of  5 "" ()
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Trace:   kolabnowCarddav.webdav                   : Syncing item: "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/09affc2c-5d97-49e2-bd44-1af488085ebc.vcf"
Trace:   kolabnowCarddav.webdav                   : Starting job:  KDAV2::DavItemFetchJob
Trace:   sinksh.resourceaccess                  : Received notification:  Notification(Type: "progress" , Id: "" , Code: 0 , Message: "" , Entities: () )
Trace:   sinksh.query.resource..resource        : Received notification in facade:  5
Trace:   sinksh.resourcecontrol                 : Received notification:  5 ""
Trace:   sinksh.resourcecontrol                 : Received notification:  5 ""
Trace:   kolabnowCarddav.webdav                   : Job done:  KDAV2::DavItemFetchJob
Trace:   kolabnowCarddav.synchronizer             : Create or modify "contact" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/09affc2c-5d97-49e2-bd44-1af488085ebc.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationrid.mapping.contact" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationlocalid.mapping.contact" false
Trace:   kolabnowCarddav.synchronizer             : Found a new entity:  "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/09affc2c-5d97-49e2-bd44-1af488085ebc.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Log:     kolabnowCarddav.synchronizer             : Progress:  2  out of  5 "" ()
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Trace:   kolabnowCarddav.webdav                   : Syncing item: "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/515968ce-f392-4d12-b99a-caec30646915.vcf"
Trace:   kolabnowCarddav.webdav                   : Starting job:  KDAV2::DavItemFetchJob
Trace:   sinksh.resourceaccess                  : Received notification:  Notification(Type: "progress" , Id: "" , Code: 0 , Message: "" , Entities: () )
Trace:   sinksh.query.resource..resource        : Received notification in facade:  5
Trace:   sinksh.resourcecontrol                 : Received notification:  5 ""
Trace:   sinksh.resourcecontrol                 : Received notification:  5 ""
Trace:   kolabnowCarddav.webdav                   : Job done:  KDAV2::DavItemFetchJob
Trace:   kolabnowCarddav.synchronizer             : Create or modify "contact" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/515968ce-f392-4d12-b99a-caec30646915.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationrid.mapping.contact" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationlocalid.mapping.contact" false
Trace:   kolabnowCarddav.synchronizer             : Found a new entity:  "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/515968ce-f392-4d12-b99a-caec30646915.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Log:     kolabnowCarddav.synchronizer             : Progress:  3  out of  5 "" ()
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Trace:   kolabnowCarddav.webdav                   : Syncing item: "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/cd97ed09-4142-478f-82fe-98e47472dd9c.vcf"
Trace:   kolabnowCarddav.webdav                   : Starting job:  KDAV2::DavItemFetchJob
Trace:   sinksh.resourceaccess                  : Received notification:  Notification(Type: "progress" , Id: "" , Code: 0 , Message: "" , Entities: () )
Trace:   sinksh.query.resource..resource        : Received notification in facade:  5
Trace:   sinksh.resourcecontrol                 : Received notification:  5 ""
Trace:   sinksh.resourcecontrol                 : Received notification:  5 ""
Trace:   kolabnowCarddav.webdav                   : Job done:  KDAV2::DavItemFetchJob
Trace:   kolabnowCarddav.synchronizer             : Create or modify "contact" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/cd97ed09-4142-478f-82fe-98e47472dd9c.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationrid.mapping.contact" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationlocalid.mapping.contact" false
Trace:   kolabnowCarddav.synchronizer             : Found a new entity:  "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/cd97ed09-4142-478f-82fe-98e47472dd9c.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Log:     kolabnowCarddav.synchronizer             : Progress:  4  out of  5 "" ()
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Trace:   kolabnowCarddav.webdav                   : Syncing item: "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/87ff8ad2-46c1-49d5-88f2-0edc28eaa6f7.vcf"
Trace:   kolabnowCarddav.webdav                   : Starting job:  KDAV2::DavItemFetchJob
Trace:   sinksh.resourceaccess                  : Received notification:  Notification(Type: "progress" , Id: "" , Code: 0 , Message: "" , Entities: () )
Trace:   sinksh.query.resource..resource        : Received notification in facade:  5
Trace:   sinksh.resourcecontrol                 : Received notification:  5 ""
Trace:   sinksh.resourcecontrol                 : Received notification:  5 ""
Trace:   kolabnowCarddav.webdav                   : Job done:  KDAV2::DavItemFetchJob
Trace:   kolabnowCarddav.synchronizer             : Create or modify "contact" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/87ff8ad2-46c1-49d5-88f2-0edc28eaa6f7.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationrid.mapping.contact" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationlocalid.mapping.contact" false
Trace:   kolabnowCarddav.synchronizer             : Found a new entity:  "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/87ff8ad2-46c1-49d5-88f2-0edc28eaa6f7.vcf"
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizerqueuedefault" false
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.synchronizationvalues" false
Log:     kolabnowCarddav.synchronizer             : Progress:  5  out of  5 "" ()
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore              : Committing transaction
Trace:   kolabnowCarddav.commandprocessor         : Cleanup done. 1 [ms]
Trace:   kolabnowCarddav.pipeline                 : Starting transaction.
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore              : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor         : Queue processed. 1 [ms]
Trace:   kolabnowCarddav.pipeline                 : Starting transaction.
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "CreateEntity"
Trace:   kolabnowCarddav.pipeline                 : New Entity. Type:  "addressbook" uid:  "{838f85a5-0492-46b4-8e71-0cff2e6337ba}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : New entity  ApplicationDomainType(
   Id:           "{838f85a5-0492-46b4-8e71-0cff2e6337ba}"
   Resource:     "kolabnowCarddav"
   "name"        "Contacts"
   "parent"      QVariant(Invalid)
 )
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddavaddressbookuids" false
Trace:   kolabnowCarddav.entitystore              : Wrote entity:  "{838f85a5-0492-46b4-8e71-0cff2e6337ba}" "addressbook" 1
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "CreateEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  1 . Processing took:  3 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "CreateEntity"
Trace:   kolabnowCarddav.pipeline                 : New Entity. Type:  "addressbook" uid:  "{cb91dc49-42e7-47fa-8247-ba3006526623}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : New entity  ApplicationDomainType(
   Id:           "{cb91dc49-42e7-47fa-8247-ba3006526623}"
   Resource:     "kolabnowCarddav"
   "name"        "Test AddressBook Collection"
   "parent"      QVariant(Invalid)
 )
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddavaddressbookuids" false
Trace:   kolabnowCarddav.entitystore              : Wrote entity:  "{cb91dc49-42e7-47fa-8247-ba3006526623}" "addressbook" 2
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "CreateEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  2 . Processing took:  1 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "CreateEntity"
Trace:   kolabnowCarddav.pipeline                 : New Entity. Type:  "contact" uid:  "{6a4879fa-921b-42e8-9258-d92df7e9cb8d}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : New entity  ApplicationDomainType(
   Id:           "{6a4879fa-921b-42e8-9258-d92df7e9cb8d}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{838f85a5-0492-46b4-8e71-0cff2e6337ba}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "John"
   "fn"          "John Doe"
   "lastname"    "Doe"
   "photo"       ""
   "uid"         "1b6c49f0-53bc-471e-8ad3-5a70424db2ad"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddavcontactuids" false
Trace:   kolabnowCarddav.entitystore              : Wrote entity:  "{6a4879fa-921b-42e8-9258-d92df7e9cb8d}" "contact" 3
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "CreateEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  3 . Processing took:  3 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "CreateEntity"
Trace:   kolabnowCarddav.pipeline                 : New Entity. Type:  "contact" uid:  "{8047f2e1-f712-49bc-87f3-62be87b19698}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : New entity  ApplicationDomainType(
   Id:           "{8047f2e1-f712-49bc-87f3-62be87b19698}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{838f85a5-0492-46b4-8e71-0cff2e6337ba}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "Kolab"
   "fn"          "Kolab Test"
   "lastname"    "Test"
   "photo"       ""
   "uid"         "09affc2c-5d97-49e2-bd44-1af488085ebc"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddavcontactuids" false
Trace:   kolabnowCarddav.entitystore              : Wrote entity:  "{8047f2e1-f712-49bc-87f3-62be87b19698}" "contact" 4
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "CreateEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  4 . Processing took:  2 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "CreateEntity"
Trace:   kolabnowCarddav.pipeline                 : New Entity. Type:  "contact" uid:  "{817b3f88-d8d6-4824-9baa-679b31f76bcf}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : New entity  ApplicationDomainType(
   Id:           "{817b3f88-d8d6-4824-9baa-679b31f76bcf}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{838f85a5-0492-46b4-8e71-0cff2e6337ba}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "test2"
   "fn"          "test2 2"
   "lastname"    "2"
   "photo"       ""
   "uid"         "515968ce-f392-4d12-b99a-caec30646915"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddavcontactuids" false
Trace:   kolabnowCarddav.entitystore              : Wrote entity:  "{817b3f88-d8d6-4824-9baa-679b31f76bcf}" "contact" 5
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "CreateEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  5 . Processing took:  1 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "CreateEntity"
Trace:   kolabnowCarddav.pipeline                 : New Entity. Type:  "contact" uid:  "{78cb4fab-d568-427a-a072-c275e3e2b9c9}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : New entity  ApplicationDomainType(
   Id:           "{78cb4fab-d568-427a-a072-c275e3e2b9c9}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{838f85a5-0492-46b4-8e71-0cff2e6337ba}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "New Mac"
   "fn"          "New Mac Test"
   "lastname"    "Test"
   "photo"       ""
   "uid"         "cd97ed09-4142-478f-82fe-98e47472dd9c"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddavcontactuids" false
Trace:   kolabnowCarddav.entitystore              : Wrote entity:  "{78cb4fab-d568-427a-a072-c275e3e2b9c9}" "contact" 6
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "CreateEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  6 . Processing took:  1 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "CreateEntity"
Trace:   kolabnowCarddav.pipeline                 : New Entity. Type:  "contact" uid:  "{2ef92d4c-a94c-4ba5-b8fa-7eabf60c9b60}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : New entity  ApplicationDomainType(
   Id:           "{2ef92d4c-a94c-4ba5-b8fa-7eabf60c9b60}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{838f85a5-0492-46b4-8e71-0cff2e6337ba}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "New"
   "fn"          "New Contact"
   "lastname"    "Contact"
   "photo"       ""
   "uid"         "87ff8ad2-46c1-49d5-88f2-0edc28eaa6f7"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddavcontactuids" false
Trace:   kolabnowCarddav.entitystore              : Wrote entity:  "{2ef92d4c-a94c-4ba5-b8fa-7eabf60c9b60}" "contact" 7
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "CreateEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  7 . Processing took:  1 [ms]
Trace:   kolabnowCarddav.pipeline                 : Committing revision:  7 : 7  items in:  60 [ms]   8.57143 [ms/item]
Trace:   kolabnowCarddav.entitystore              : Committing transaction
Trace:   kolabnowCarddav.listener                 : Sending revision update for  "PID: 64 ResourceAccess: 32049232" 7
Trace:   kolabnowCarddav.commandprocessor         : Queue processed. 114 [ms]
Trace:   sinksh.resourceaccess                  : Received notification:  Notification(Type: "progress" , Id: "" , Code: 0 , Message: "" , Entities: () )
Trace:   sinksh.query.resource..resource        : Received notification in facade:  5
Trace:   sinksh.resourcecontrol                 : Received notification:  5 ""
Trace:   sinksh.resourcecontrol                 : Received notification:  5 ""
Trace:   sinksh.resourceaccess                  : "Revision updated to: 7"
Trace:   sinksh.query.resource..resource        : Received notification in facade:  7
Trace:   sinksh.resourcecontrol                 : Received notification:  7 ""
Trace:   sinksh.resourcecontrol                 : Received notification:  7 ""
Trace:   kolabnowCarddav.synchronizer             : Starting transaction on sync store.
Trace:   kolabnowCarddav.webdav                   : Scanning for entity removals
Trace:   kolabnowCarddav.storage_lmdb             : Found dbi that is not available for the current transaction.
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{2ef92d4c-a94c-4ba5-b8fa-7eabf60c9b60}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/87ff8ad2-46c1-49d5-88f2-0edc28eaa6f7.vcf"
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{6a4879fa-921b-42e8-9258-d92df7e9cb8d}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/1b6c49f0-53bc-471e-8ad3-5a70424db2ad.vcf"
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{78cb4fab-d568-427a-a072-c275e3e2b9c9}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/cd97ed09-4142-478f-82fe-98e47472dd9c.vcf"
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{8047f2e1-f712-49bc-87f3-62be87b19698}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/09affc2c-5d97-49e2-bd44-1af488085ebc.vcf"
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{817b3f88-d8d6-4824-9baa-679b31f76bcf}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/515968ce-f392-4d12-b99a-caec30646915.vcf"
Trace:   kolabnowCarddav.webdav                   : Syncing collection: "/addressbooks/test1@kolab.org/{55399646-c2d3-438f-a475-b04d67fadf54}/"
Trace:   kolabnowCarddav.webdav                   : Starting job:  KDAV2::DavItemsListJob
Trace:   kolabnowCarddav.synchronizer             : Sync still in progress.
Trace:   kolabnowCarddav.webdav                   : Job done:  KDAV2::DavItemsListJob
Trace:   kolabnowCarddav.webdav                   : Scanning for entity removals
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{2ef92d4c-a94c-4ba5-b8fa-7eabf60c9b60}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/87ff8ad2-46c1-49d5-88f2-0edc28eaa6f7.vcf"
Trace:   kolabnowCarddav.synchronizer             : Found a removed entity:  "{2ef92d4c-a94c-4ba5-b8fa-7eabf60c9b60}"
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{6a4879fa-921b-42e8-9258-d92df7e9cb8d}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/1b6c49f0-53bc-471e-8ad3-5a70424db2ad.vcf"
Trace:   kolabnowCarddav.synchronizer             : Found a removed entity:  "{6a4879fa-921b-42e8-9258-d92df7e9cb8d}"
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{78cb4fab-d568-427a-a072-c275e3e2b9c9}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/cd97ed09-4142-478f-82fe-98e47472dd9c.vcf"
Trace:   kolabnowCarddav.synchronizer             : Found a removed entity:  "{78cb4fab-d568-427a-a072-c275e3e2b9c9}"
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{8047f2e1-f712-49bc-87f3-62be87b19698}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/09affc2c-5d97-49e2-bd44-1af488085ebc.vcf"
Trace:   kolabnowCarddav.synchronizer             : Found a removed entity:  "{8047f2e1-f712-49bc-87f3-62be87b19698}"
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{817b3f88-d8d6-4824-9baa-679b31f76bcf}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/515968ce-f392-4d12-b99a-caec30646915.vcf"
Trace:   kolabnowCarddav.synchronizer             : Found a removed entity:  "{817b3f88-d8d6-4824-9baa-679b31f76bcf}"
Trace:   kolabnowCarddav.webdav                   : Scanning for collection removals
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{838f85a5-0492-46b4-8e71-0cff2e6337ba}" "/addressbooks/test1@kolab.org/f7a551fa-996f-497e-bad3-567e8a8648d8/"
Trace:   kolabnowCarddav.synchronizer             : Checking for removal  "{cb91dc49-42e7-47fa-8247-ba3006526623}" "/addressbooks/test1@kolab.org/{55399646-c2d3-438f-a475-b04d67fadf54}/"
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore              : Committing transaction
Trace:   kolabnowCarddav.commandprocessor         : Cleanup done. 0 [ms]
Trace:   kolabnowCarddav.pipeline                 : Starting transaction.
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore              : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor         : Queue processed. 1 [ms]
Trace:   kolabnowCarddav.pipeline                 : Starting transaction.
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "DeleteEntity"
Trace:   kolabnowCarddav.pipeline                 : Deleted Entity. Type:  "contact" uid:  "{78cb4fab-d568-427a-a072-c275e3e2b9c9}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : Removed entity  ApplicationDomainType(
   Id:           "{78cb4fab-d568-427a-a072-c275e3e2b9c9}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{838f85a5-0492-46b4-8e71-0cff2e6337ba}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "New Mac"
   "fn"          "New Mac Test"
   "lastname"    "Test"
   "photo"       ""
   "uid"         "cd97ed09-4142-478f-82fe-98e47472dd9c"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "DeleteEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  8 . Processing took:  1 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "DeleteEntity"
Trace:   kolabnowCarddav.pipeline                 : Deleted Entity. Type:  "contact" uid:  "{8047f2e1-f712-49bc-87f3-62be87b19698}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : Removed entity  ApplicationDomainType(
   Id:           "{8047f2e1-f712-49bc-87f3-62be87b19698}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{838f85a5-0492-46b4-8e71-0cff2e6337ba}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "Kolab"
   "fn"          "Kolab Test"
   "lastname"    "Test"
   "photo"       ""
   "uid"         "09affc2c-5d97-49e2-bd44-1af488085ebc"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "DeleteEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  9 . Processing took:  1 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "DeleteEntity"
Trace:   kolabnowCarddav.pipeline                 : Deleted Entity. Type:  "contact" uid:  "{817b3f88-d8d6-4824-9baa-679b31f76bcf}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : Removed entity  ApplicationDomainType(
   Id:           "{817b3f88-d8d6-4824-9baa-679b31f76bcf}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{838f85a5-0492-46b4-8e71-0cff2e6337ba}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "test2"
   "fn"          "test2 2"
   "lastname"    "2"
   "photo"       ""
   "uid"         "515968ce-f392-4d12-b99a-caec30646915"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "DeleteEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  10 . Processing took:  1 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "DeleteEntity"
Trace:   kolabnowCarddav.pipeline                 : Deleted Entity. Type:  "contact" uid:  "{2ef92d4c-a94c-4ba5-b8fa-7eabf60c9b60}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : Removed entity  ApplicationDomainType(
   Id:           "{2ef92d4c-a94c-4ba5-b8fa-7eabf60c9b60}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{838f85a5-0492-46b4-8e71-0cff2e6337ba}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "New"
   "fn"          "New Contact"
   "lastname"    "Contact"
   "photo"       ""
   "uid"         "87ff8ad2-46c1-49d5-88f2-0edc28eaa6f7"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "DeleteEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  11 . Processing took:  1 [ms]
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "DeleteEntity"
Trace:   kolabnowCarddav.pipeline                 : Deleted Entity. Type:  "contact" uid:  "{6a4879fa-921b-42e8-9258-d92df7e9cb8d}"  replayToSource:  false
Trace:   kolabnowCarddav.entitystore              : Removed entity  ApplicationDomainType(
   Id:           "{6a4879fa-921b-42e8-9258-d92df7e9cb8d}"
   Resource:     "kolabnowCarddav"
   "addressbook"         "{838f85a5-0492-46b4-8e71-0cff2e6337ba}"
   "emails"      QVariant(QList<Sink::ApplicationDomain::Contact::Email>, )
   "firstname"   "John"
   "fn"          "John Doe"
   "lastname"    "Doe"
   "photo"       ""
   "uid"         "1b6c49f0-53bc-471e-8ad3-5a70424db2ad"
   "vcard"       "BEGIN:VCARD\r\nVERSION:3.0\r\nPRODID:-//Kolab//iRony DAV Server 0.4-dev//Sabre/"
 )
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "DeleteEntity"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  12 . Processing took:  1 [ms]
Trace:   kolabnowCarddav.pipeline                 : Committing revision:  12 : 5  items in:  71 [ms]   14.2 [ms/item]
Trace:   kolabnowCarddav.entitystore              : Committing transaction
Trace:   kolabnowCarddav.listener                 : Sending revision update for  "PID: 64 ResourceAccess: 32049232" 12
Trace:   kolabnowCarddav.commandprocessor         : Queue processed. 126 [ms]
Trace:   sinksh.resourceaccess                  : "Revision updated to: 12"
Trace:   sinksh.query.resource..resource        : Received notification in facade:  7
Trace:   sinksh.resourcecontrol                 : Received notification:  7 ""
Trace:   sinksh.resourcecontrol                 : Received notification:  7 ""
Log:     kolabnowCarddav.synchronizer             : Done Synchronizing
Trace:   kolabnowCarddav.synchronizer             : Sync request processed
Trace:   kolabnowCarddav.synchronizer.entitystore : Aborting transaction
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore              : Committing transaction
Trace:   kolabnowCarddav.commandprocessor         : Cleanup done. 0 [ms]
Trace:   kolabnowCarddav.pipeline                 : Starting transaction.
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore              : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor         : Queue processed. 1 [ms]
Trace:   kolabnowCarddav.pipeline                 : Starting transaction.
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore              : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor         : Queue processed. 1 [ms]
Trace:   kolabnowCarddav.synchronizer             : Queue is not empty
Trace:   kolabnowCarddav.synchronizer.entitystore : Starting transaction:  0
Trace:   kolabnowCarddav.synchronizer             : Sync request processed
Trace:   kolabnowCarddav.synchronizer.entitystore : Aborting transaction
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore              : Committing transaction
Trace:   kolabnowCarddav.commandprocessor         : Cleanup done. 0 [ms]
Trace:   kolabnowCarddav.pipeline                 : Starting transaction.
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore              : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor         : Queue processed. 1 [ms]
Trace:   kolabnowCarddav.pipeline                 : Starting transaction.
Trace:   kolabnowCarddav.entitystore              : Starting transaction:  1
Trace:   kolabnowCarddav.commandprocessor         : Processing command:  "Flush"
Trace:   kolabnowCarddav.commandprocessor         : Emitting flush completion "{91659d75-0d1f-4a56-ab2a-196b4a2ff39b}"
Trace:   kolabnowCarddav.synchronizer             : Flush complete:  "{91659d75-0d1f-4a56-ab2a-196b4a2ff39b}"
Trace:   kolabnowCarddav.commandprocessor         : Command pipeline processed:  "Flush"
Trace:   kolabnowCarddav.commandprocessor         : Created revision  -1 . Processing took:  1 [ms]
Trace:   kolabnowCarddav.entitystore              : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor         : Queue processed. 16 [ms]
Trace:   kolabnowCarddav.synchronizer             : Queue is not empty
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.changereplaydefault" true
Trace:   kolabnowCarddav.synchronizer.entitystore : Starting transaction:  0
Log:     kolabnowCarddav.synchronizer             : Replaying changes.
Trace:   kolabnowCarddav.storage_lmdb             : Creating database dynamically:  "kolabnowCarddav.changereplaydefault" true
Trace:   kolabnowCarddav.synchronizer.changereplay : Changereplay from  0  to  12
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{838f85a5-0492-46b4-8e71-0cff2e6337ba}0000000000000000001"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{cb91dc49-42e7-47fa-8247-ba3006526623}0000000000000000002"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{6a4879fa-921b-42e8-9258-d92df7e9cb8d}0000000000000000003"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{8047f2e1-f712-49bc-87f3-62be87b19698}0000000000000000004"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{817b3f88-d8d6-4824-9baa-679b31f76bcf}0000000000000000005"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{78cb4fab-d568-427a-a072-c275e3e2b9c9}0000000000000000006"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{2ef92d4c-a94c-4ba5-b8fa-7eabf60c9b60}0000000000000000007"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{78cb4fab-d568-427a-a072-c275e3e2b9c9}0000000000000000008"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{8047f2e1-f712-49bc-87f3-62be87b19698}0000000000000000009"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{817b3f88-d8d6-4824-9baa-679b31f76bcf}0000000000000000010"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{2ef92d4c-a94c-4ba5-b8fa-7eabf60c9b60}0000000000000000011"
Trace:   kolabnowCarddav.synchronizer              : Change is coming from the source
Trace:   kolabnowCarddav.synchronizer.changereplay : Not replaying  "{6a4879fa-921b-42e8-9258-d92df7e9cb8d}0000000000000000012"
Trace:   kolabnowCarddav.synchronizer.changereplay : Replayed until:  12
Trace:   kolabnowCarddav.storage_lmdb              : Creating database dynamically:  "kolabnowCarddav.changereplaydefault" false
Log:     kolabnowCarddav.synchronizer              : Progress:  12  out of  12 "changereplay" ()
Trace:   kolabnowCarddav.synchronizer.changereplay : Change replay complete.
Trace:   kolabnowCarddav.synchronizer.changereplay : All changes replayed
Log:     kolabnowCarddav.synchronizer              : Done replaying changes
Trace:   kolabnowCarddav.synchronizer              : Sync request processed
Trace:   kolabnowCarddav.synchronizer.entitystore  : Aborting transaction
Trace:   kolabnowCarddav.entitystore               : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore               : Committing transaction
Trace:   kolabnowCarddav.commandprocessor          : Cleanup done. 0 [ms]
Trace:   kolabnowCarddav.pipeline                  : Starting transaction.
Trace:   kolabnowCarddav.entitystore               : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore               : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor          : Queue processed. 1 [ms]
Trace:   kolabnowCarddav.pipeline                  : Starting transaction.
Trace:   kolabnowCarddav.entitystore               : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore               : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor          : Queue processed. 1 [ms]
Log:     kolabnowCarddav.synchronizer              : All requests processed.
Trace:   kolabnowCarddav.synchronizer.entitystore  : Starting transaction:  0
Trace:   kolabnowCarddav.synchronizer              : Sync request processed
Trace:   kolabnowCarddav.synchronizer.entitystore  : Aborting transaction
Trace:   kolabnowCarddav.entitystore               : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore               : Committing transaction
Trace:   kolabnowCarddav.commandprocessor          : Cleanup done. 0 [ms]
Trace:   kolabnowCarddav.pipeline                  : Starting transaction.
Trace:   kolabnowCarddav.entitystore               : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore               : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor          : Queue processed. 1 [ms]
Trace:   kolabnowCarddav.pipeline                  : Starting transaction.
Trace:   kolabnowCarddav.entitystore               : Starting transaction:  1
Trace:   kolabnowCarddav.entitystore               : Aborting transaction
Trace:   kolabnowCarddav.commandprocessor          : Queue processed. 1 [ms]
Log:     kolabnowCarddav.synchronizer              : All requests processed.
Trace:   sinksh.resourceaccess                  : Updated status:  2
Trace:   sinksh.resourceaccess                  : Received notification:  Notification(Type: "status" , Id: "" , Code: 2 , Message: "Synchronization has ended." , Entities: () )
Trace:   sinksh.query.resource..resource        : Received notification in facade:  1
Trace:   sinksh.query.resource..resource.config : Status changed  "kolabnowCarddav"
Trace:   sinksh.query.resource.modelresult      : Received modification:  "kolabnowCarddav"
Trace:   sinksh.query.resource.modelresult      : Modified entity: "kolabnowCarddav" , id:  371607868
Trace:   sinksh.resourcecontrol                 : Received notification:  1 ""
Trace:   sinksh.resourcecontrol                 : Received notification:  1 ""
Trace:   sinksh.resourceaccess                  : Received notification:  Notification(Type: "info" , Id: "" , Code: 3 , Message: "" , Entities: () )
Trace:   sinksh.query.resource..resource        : Received notification in facade:  2
Trace:   sinksh.resourcecontrol                 : Received notification:  2 ""
Trace:   sinksh.resourcecontrol                 : Received notification:  2 ""
Trace:   sinksh.resourceaccess                  : Received notification:  Notification(Type: "flushcompletion" , Id: "{91659d75-0d1f-4a56-ab2a-196b4a2ff39b}" , Code: 0 , Message: "" , Entities: () )
Trace:   sinksh.query.resource..resource        : Received notification in facade:  8
Trace:   sinksh.resourcecontrol                 : Received notification:  8 "{91659d75-0d1f-4a56-ab2a-196b4a2ff39b}"
Trace:   sinksh.resourcecontrol                 : Received notification:  8 "{91659d75-0d1f-4a56-ab2a-196b4a2ff39b}"
Trace:   sinksh.resourcecontrol                 : FlushComplete
Synchronization complete!
Trace:   sinksh.state                           : Command finished. Exit code:  0
Trace:   sinksh.resourceaccess                  : Updated status:  3
Trace:   sinksh.resourceaccess                  : Received notification:  Notification(Type: "status" , Id: "changereplay" , Code: 3 , Message: "ChangeReplay has started." , Entities: () )
Trace:   sinksh.resourceaccess                  : Received notification:  Notification(Type: "progress" , Id: "changereplay" , Code: 0 , Message: "" , Entities: () )
Trace:   sinksh.resourceaccess                  : Updated status:  2
Trace:   sinksh.resourceaccess                  : Received notification:  Notification(Type: "status" , Id: "changereplay" , Code: 2 , Message: "Changereplay has ended." , Entities: () )
Log:     kolabnowCarddav.listener                  : "Dropped connection: PID: 64 ResourceAccess: 32049232" QLocalSocket(0x17c6a60)
bash-4.4$ Trace:   kolabnowCarddav.listener                  : "No connections, shutting down."
Trace:   kolabnowCarddav.listener                  : Quitting  "kolabnowCarddav"
Log:     kolabnowCarddav.main                      : Exiting:  "kolabnowCarddav"
Log:     kolabnowCarddav.main                      : Program size: 128165673 pages
Log:     kolabnowCarddav.main                      : RSS: 12059 pages
Log:     kolabnowCarddav.main                      : Resident Shared: 10449 pages
Log:     kolabnowCarddav.main                      : Text (code): 16 pages
Log:     kolabnowCarddav.main                      : Data (data + stack): 15592 pages
Log:     kolabnowCarddav.main                      : Minor page faults:  630
Log:     kolabnowCarddav.main                      : Children minor page faults:  5
Log:     kolabnowCarddav.main                      : Major page faults:  0
Log:     kolabnowCarddav.main                      : Children major page faults:  16
Log:     kolabnowCarddav.main                      : Read syscalls:  561
Log:     kolabnowCarddav.main                      : Write syscalls:  1065
Log:     kolabnowCarddav.main                      : Read from disk:  264 kb
Log:     kolabnowCarddav.main                      : Written to disk:  388 kb
Log:     kolabnowCarddav.main                      : Cancelled write bytes:  0
Trace:   kolabnowCarddav.listener                  : Shutting down  "kolabnowCarddav"
cmollekopf closed this task as Resolved.Aug 17 2018, 8:51 PM
cmollekopf claimed this task.

It didn't take multiple collections into account.