Unable to sync Contacts



  • Hi,
    I have a z-push 2.5 working with a vcf files backend (an old provider based on the originally one provided with z-push).
    If I connect an old Windows Phone, all ok.
    If I connect a new Android device, it dowloads all contacts well (more that 800, 10 every http call, all seems ok and I can see them in Android contacts list growing during sync). Then, just after the last one has been downloaded, it clears all downloaded contacts from the phone. At next sync, it starts again to download all from scratch.

    Logs seems not reporting anything useful. They only reports some loop alert with first contacts in download, and only starting from the second sync (so it makes some sense).

    Any idea? :(

    thanks


  • Kopano

    @Trapulo said in Unable to sync Contacts:

    Any idea? :(

    please provide a wbxml log of the process



  • How can I share an about 100 KB log?

    thanks


  • Kopano

    @Trapulo you could upload it to pastebin for example. I would imagine the most relevant part is when he has finished syncing the folder and is deciding to do a resync.



  • I tried to paste, but it’s too big :(

    This is the log AFTER all contacts has been downloaded:

    30/01/2020 16:38:54 [ 9660] [DEBUG] [user@sync.domain.com] HandleSync(): Exported maxItems of messages: 10 / 757
    30/01/2020 16:38:54 [ 9660] [WBXML] [user@sync.domain.com] O     </Perform>
    30/01/2020 16:38:54 [ 9660] [WBXML] [user@sync.domain.com] O    </Folder>
    30/01/2020 16:38:54 [ 9660] [DEBUG] [user@sync.domain.com] FileStateMachine->SetState() written 9527 bytes on file: 'd:\sync\states/4/4/androidc284042444-573abf72-8edd-4c49-b327-da3efc8c4b99-13'
    30/01/2020 16:38:54 [ 9660] [DEBUG] [user@sync.domain.com] SyncCollections->SaveCollection(): Data of folder 'root' changed
    30/01/2020 16:38:54 [ 9660] [DEBUG] [user@sync.domain.com] FileStateMachine->SetState() written 895 bytes on file: 'd:\sync\states/4/4/androidc284042444-573abf72-8edd-4c49-b327-da3efc8c4b99-fd'
    30/01/2020 16:38:54 [ 9660] [WBXML] [user@sync.domain.com] O   </Folders>
    30/01/2020 16:38:54 [ 9660] [WBXML] [user@sync.domain.com] O  </Synchronize>
    30/01/2020 16:38:54 [ 9660] [DEBUG] [user@sync.domain.com] WBXMLEncoder->endTag() WBXML output completed
    30/01/2020 16:38:54 [ 9660] [WBXML] [user@sync.domain.com] WBXML-OUT: AwFqAEVcT0sDezU3M2FiZjcyLThlZGQtNG
    privacy cut
    
    30/01/2020 16:38:54 [ 9660] [WBXML] [user@sync.domain.com] WBXML-IN : AwFqAEVcT0sDezU3M2FiZjcyLThlZGQtNGM0OS1iMzI3LWRhM2VmYzhjNGI5OX0xMgABUgNyb290AAEeUwMxAAFVAzEwAAFXABFFRgMxAAFHAzIwMDAwMAABAQEBAQE=
    30/01/2020 16:38:54 [ 9660] [DEBUG] [user@sync.domain.com] LoopDetection->ProcessLoopDetectionTerminate()
    30/01/2020 16:38:54 [ 9660] [ INFO] [user@sync.domain.com] cmd='Sync' memory='2.71 MiB/2.00 MiB' time='5.11s' devType='Android' devId='androidc284042444' getUser='user@sync.domain.com' from='1.2.3.4' idle='0s' version='GIT' method='POST' httpcode='200'
    30/01/2020 16:38:54 [ 9660] [DEBUG] [user@sync.domain.com] -------- End
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] -------- Start
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] cmd='FolderSync' devType='Android' devId='androidc284042444' getUser='user@sync.domain.com' from='1.2.3.4' version='GIT' method='POST'
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] Used timezone 'Europe/Rome'
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] Request::ProcessHeaders() ASVersion: 14.0
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] ZPush::CommandNeedsAuthentication(9): true
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] FileStateMachine->GetState() read '1630' bytes from file: 'd:\sync\states/4/4/androidc284042444-devicedata'
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] ASDevice data loaded for user: 'user@sync.domain.com'
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] InterProcessData:__construct type: 'androidc284042444'
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] TopCollector initialised with IPC provider 'IpcWincacheProvider' with type '20'
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] IpcWincacheProvider->HasData(): no data found for key '20:2'
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] InterProcessData:__construct type: 'androidc284042444'
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] LoopDetection initialised with IPC provider 'IpcWincacheProvider' with type 'androidc284042444-1337'
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] ZPush::HierarchyCommand(9): true
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] ZPush::CommandNeedsPlainInput(9): false
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] I  <FolderHierarchy:FolderSync>
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] I   <FolderHierarchy:SyncKey>
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] I    {36450325-877c-481c-9306-014a422f5178}1
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] I   </FolderHierarchy:SyncKey>
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] StateManager->loadHierarchyCache(): 'androidc284042444-36450325-877c-481c-9306-014a422f5178-hc-1'
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] FileStateMachine->GetState() read '590' bytes from file: 'd:\sync\states/4/4/androidc284042444-36450325-877c-481c-9306-014a422f5178-hc-1'
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] FileStateMachine->GetState() read '248' bytes from file: 'd:\sync\states/4/4/androidc284042444-36450325-877c-481c-9306-014a422f5178-1'
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] FileStateMachine->GetState() read '372' bytes from file: 'd:\sync\states/4/4/androidc284042444-36450325-877c-481c-9306-014a422f5178-fd'
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] SyncParameters->UseCPO('DEFAULT')
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 3
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] I  </FolderHierarchy:FolderSync>
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] ExportChangesDiff->InitializeExporter(): Initializing folder diff engine
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] VCDir::GetFolderList()
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] VCDir::StatFolder(root)
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] VCDir::GetFolder(root)
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] VCDir::StatFolder(Inbox)
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] VCDir::GetFolder(Inbox)
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] Creating fake Inbox Items folder
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] VCDir::StatFolder(Deleted Items)
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] VCDir::GetFolder(Deleted Items)
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] Creating fake Deleted Items folder
    30/01/2020 16:38:58 [ 9660] [ INFO] [user@sync.domain.com] ExportChangesDiff->InitializeExporter(): Found '0' changes for 'hierarchy'
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] O  <FolderHierarchy:FolderSync>
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] O   <FolderHierarchy:Status>
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] O   1
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] O   </FolderHierarchy:Status>
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] O   <FolderHierarchy:SyncKey>
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] O   {36450325-877c-481c-9306-014a422f5178}1
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] O   </FolderHierarchy:SyncKey>
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] O   <FolderHierarchy:Changes>
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] O    <FolderHierarchy:Count>
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] O    0
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] O    </FolderHierarchy:Count>
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] O   </FolderHierarchy:Changes>
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] DeviceManager->CheckFolderData() checking integrity of hierarchy cache with synchronized folders
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 3
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] DeviceManager->ProvisioningRequired('-1') saved device key '-1': true
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] FileStateMachine->SetState() written 372 bytes on file: 'd:\sync\states/4/4/androidc284042444-36450325-877c-481c-9306-014a422f5178-fd'
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] O  </FolderHierarchy:FolderSync>
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] WBXMLEncoder->endTag() WBXML output completed
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] WBXML-OUT: AwFqAAAHVkwDMQABUgN7MzY0NTAzMjUtODc3Yy00ODFjLTkzMDYtMDE0YTQyMmY1MTc4fTEAAU5XAzAAAQEB
    30/01/2020 16:38:58 [ 9660] [WBXML] [user@sync.domain.com] WBXML-IN : AwFqAAAHVlIDezM2NDUwMzI1LTg3N2MtNDgxYy05MzA2LTAxNGE0MjJmNTE3OH0xAAEB
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] LoopDetection->ProcessLoopDetectionTerminate()
    30/01/2020 16:38:58 [ 9660] [ INFO] [user@sync.domain.com] cmd='FolderSync' memory='1.87 MiB/2.00 MiB' time='0.11s' devType='Android' devId='androidc284042444' getUser='user@sync.domain.com' from='1.2.3.4' idle='0s' version='GIT' method='POST' httpcode='200'
    30/01/2020 16:38:58 [ 9660] [DEBUG] [user@sync.domain.com] -------- End
    30/01/2020 16:39:01 [ 9660] [DEBUG] [user@sync.domain.com] -------- Start
    30/01/2020 16:39:01 [ 9660] [DEBUG] [user@sync.domain.com] cmd='Settings' devType='Android' devId='androidc284042444' getUser='user@sync.domain.com' from='1.2.3.4' version='GIT' method='POST'
    30/01/2020 16:39:01 [ 9660] [DEBUG] [user@sync.domain.com] Used timezone 'Europe/Rome'
    30/01/2020 16:39:01 [ 9660] [DEBUG] [user@sync.domain.com] Request::ProcessHeaders() ASVersion: 14.0
    30/01/2020 16:39:01 [ 9660] [DEBUG] [user@sync.domain.com] ZPush::CommandNeedsAuthentication(17): true
    30/01/2020 16:39:01 [ 9660] [DEBUG] [user@sync.domain.com] FileStateMachine->GetState() read '1630' bytes from file: 'd:\sync\states/4/4/androidc284042444-devicedata'
    30/01/2020 16:39:01 [ 9660] [DEBUG] [user@sync.domain.com] ASDevice data loaded for user: 'user@sync.domain.com'
    30/01/2020 16:39:01 [ 9660] [DEBUG] [user@sync.domain.com] InterProcessData:__construct type: 'androidc284042444'
    30/01/2020 16:39:01 [ 9660] [DEBUG] [user@sync.domain.com] TopCollector initialised with IPC provider 'IpcWincacheProvider' with type '20'
    30/01/2020 16:39:01 [ 9660] [DEBUG] [user@sync.domain.com] IpcWincacheProvider->HasData(): no data found for key '20:2'
    30/01/2020 16:39:01 [ 9660] [DEBUG] [user@sync.domain.com] InterProcessData:__construct type: 'androidc284042444'
    30/01/2020 16:39:01 [ 9660] [DEBUG] [user@sync.domain.com] LoopDetection initialised with IPC provider 'IpcWincacheProvider' with type 'androidc284042444-1337'
    30/01/2020 16:39:01 [ 9660] [DEBUG] [user@sync.domain.com] ZPush::HierarchyCommand(17): false
    30/01/2020 16:39:01 [ 9660] [DEBUG] [user@sync.domain.com] ZPush::CommandNeedsPlainInput(17): false
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] I  <Settings:Settings>
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] I   <Settings:Oof>
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] I    <Settings:Get>
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] I     <Settings:BodyType>
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] I      Text
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] I     </Settings:BodyType>
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] I    </Settings:Get>
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] I   </Settings:Oof>
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] I  </Settings:Settings>
    30/01/2020 16:39:01 [ 9660] [DEBUG] [user@sync.domain.com] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] O  <Settings:Settings>
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] O   <Settings:Status>
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] O   1
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] O   </Settings:Status>
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] O   <Settings:Oof>
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] O    <Settings:Status>
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] O    1
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] O    </Settings:Status>
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] O    <Settings:Get>
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] O     <Settings:OofState>
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] O     0
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] O     </Settings:OofState>
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] O    </Settings:Get>
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] O   </Settings:Oof>
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] O  </Settings:Settings>
    30/01/2020 16:39:01 [ 9660] [DEBUG] [user@sync.domain.com] WBXMLEncoder->endTag() WBXML output completed
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] WBXML-OUT: AwFqAAASRUYDMQABSUYDMQABR0oDMAABAQEB
    30/01/2020 16:39:01 [ 9660] [WBXML] [user@sync.domain.com] WBXML-IN : AwFqAAASRUlHUwNUZXh0AAEBAQE=
    30/01/2020 16:39:01 [ 9660] [DEBUG] [user@sync.domain.com] Special header: X-Push-Capabilities: gab,receiveflags,sendflags,ooftime,notes,sharedfolder,sendas,secondarycontacts,signatures,receipts,impersonate
    30/01/2020 16:39:01 [ 9660] [DEBUG] [user@sync.domain.com] LoopDetection->ProcessLoopDetectionTerminate()
    30/01/2020 16:39:01 [ 9660] [ INFO] [user@sync.domain.com] cmd='Settings' memory='2.00 MiB/2.00 MiB' time='0.18s' devType='Android' devId='androidc284042444' getUser='user@sync.domain.com' from='1.2.3.4' idle='0s' version='GIT' method='POST' httpcode='200'
    30/01/2020 16:39:01 [ 9660] [DEBUG] [user@sync.domain.com] -------- End
    

  • Kopano

    @Trapulo said in Unable to sync Contacts:

    I tried to paste, but it’s too big :(

    autocorrect. it was meant to spell https://pastebin.com/.

    From the log you posted there is no indication as to why it resyncs, in fact there is no resync in it.



  • @fbartels That’s the point: it seems that the sync is ok. But a second after it has been completes, the phone will delete all downloaded contacts from its memory.

    you can find here the full log of the first sync: https://pastebin.com/ftQ20PAG

    then I’ll post a log of the next sync (after the phone has deleted all contacts downloaded before)



  • this is a sync after the first one.
    Also during this sync all contacts was download, and visible on the phone, but then after sync has completed all them was removed from the phone address book.

    https://pastebin.com/uqMUWM6X


  • Kopano

    Hi @Trapulo,

    In the first log after syncing the initial batch of the contacts the device doesn’t continue with the contacts’ sync but issues FolderSync request and then probably starts contact folder sync from 0 again (only assumption as you haven’t posted the log after Settings request).

    In the second posted log it looks like the contact sync failed (or you have cut too much as there’s no “-------- End” marking the request end). Then it also continues with FolderSync and probably with contact folder sync from 0.

    I also don’t see any indication that all the contacts were synced, but that might be due to the fact that you cut out parts of the log.

    Manfred



  • Hi @Manfred ,
    here there are rows with the end missing. The rows at the end of the “…contacts sync…” of the second log.
    My opinion is that the sync seems ok, still this XXX#! Android is removing all contacts after the sync.

    04/02/2020 09:32:11 [17004] [WBXML] [user@sync.domain.com] [androidc284042444] O </POOMCONTACTS:FileAs>
    04/02/2020 09:32:11 [17004] [WBXML] [user@sync.domain.com] [androidc284042444] O POOMCONTACTS:FirstName
    04/02/2020 09:32:11 [17004] [WBXML] [user@sync.domain.com] [androidc284042444] O xxxxxxxxxxxxxxxxxxxx
    04/02/2020 09:32:11 [17004] [WBXML] [user@sync.domain.com] [androidc284042444] O </POOMCONTACTS:FirstName>
    04/02/2020 09:32:11 [17004] [WBXML] [user@sync.domain.com] [androidc284042444] O POOMCONTACTS:MobilePhoneNumber
    04/02/2020 09:32:11 [17004] [WBXML] [user@sync.domain.com] [androidc284042444] O +xxxxxxxxxxxxxxxxxxxx
    04/02/2020 09:32:11 [17004] [WBXML] [user@sync.domain.com] [androidc284042444] O </POOMCONTACTS:MobilePhoneNumber>
    04/02/2020 09:32:11 [17004] [WBXML] [user@sync.domain.com] [androidc284042444] O </Data>
    04/02/2020 09:32:11 [17004] [WBXML] [user@sync.domain.com] [androidc284042444] O </Add>
    04/02/2020 09:32:11 [17004] [WBXML] [user@sync.domain.com] [androidc284042444] O </Perform>
    04/02/2020 09:32:11 [17004] [DEBUG] [user@sync.domain.com] [androidc284042444] SetFolderSyncStatus(): completed for root
    04/02/2020 09:32:11 [17004] [WBXML] [user@sync.domain.com] [androidc284042444] O </Folder>
    04/02/2020 09:32:11 [17004] [DEBUG] [user@sync.domain.com] [androidc284042444] FileStateMachine->SetState() written 70226 bytes on file: ‘d:\sync\states/4/4/androidc284042444-69c3ad88-c7d5-4720-a5ad-dd01892ae5c9-88’
    04/02/2020 09:32:11 [17004] [DEBUG] [user@sync.domain.com] [androidc284042444] SyncCollections->SaveCollection(): Data of folder ‘root’ changed
    04/02/2020 09:32:11 [17004] [DEBUG] [user@sync.domain.com] [androidc284042444] FileStateMachine->SetState() written 893 bytes on file: ‘d:\sync\states/4/4/androidc284042444-69c3ad88-c7d5-4720-a5ad-dd01892ae5c9-fd’
    04/02/2020 09:32:11 [17004] [WBXML] [user@sync.domain.com] [androidc284042444] O </Folders>
    04/02/2020 09:32:11 [17004] [WBXML] [user@sync.domain.com] [androidc284042444] O </Synchronize>
    04/02/2020 09:32:11 [17004] [DEBUG] [user@sync.domain.com] [androidc284042444] WBXMLEncoder->endTag() WBXML output completed
    04/02/2020 09:32:11 [17004] [WBXML] [user@sync.domain.com] [androidc284042444] WBXML-OUT: AwFqAEVcT0sDezY5YzNhZDg4LWM3ZDUtNDcyMC1hNWFkLWRkMDE4OTJhZTVjOX04OAABUgNyb290AAFOAzEAAV privacy cut…
    04/02/2020 09:32:11 [17004] [WBXML] [user@sync.domain.com] [androidc284042444] WBXML-IN : AwFqAEVcT0sDezY5YzNhZDg4LWM3ZDUtNDcyMC1hNWFkLWRkMDE4OTJhZTVjOX04NwABUgNyb290AAEeUwMxAAFVAzEwAAFXABFFRgMxAAFHAzIwMDAwMAABAQEBAQE=
    04/02/2020 09:32:11 [17004] [DEBUG] [user@sync.domain.com] [androidc284042444] DeviceManager->Save(): Device data changed
    04/02/2020 09:32:11 [17004] [DEBUG] [user@sync.domain.com] [androidc284042444] FileStateMachine->SetState() written 1612 bytes on file: ‘d:\sync\states/4/4/androidc284042444-devicedata’
    04/02/2020 09:32:11 [17004] [DEBUG] [user@sync.domain.com] [androidc284042444] DeviceManager->Save(): Device data saved
    04/02/2020 09:32:11 [17004] [DEBUG] [user@sync.domain.com] [androidc284042444] LoopDetection->ProcessLoopDetectionTerminate()
    04/02/2020 09:32:11 [17004] [ INFO] [user@sync.domain.com] [androidc284042444] cmd=‘Sync’ memory=‘2.87 MiB/2.00 MiB’ time=‘3.42s’ devType=‘Android’ devId=‘androidc284042444’ getUser=‘user@sync.domain.com’ from=‘109.168.66.38’ idle=‘0s’ version=‘GIT’ method=‘POST’ httpcode=‘200’
    04/02/2020 09:32:11 [17004] [DEBUG] [user@sync.domain.com] [androidc284042444] -------- End
    04/02/2020 09:32:12 [17004] [DEBUG] [user@sync.domain.com] [androidc284042444] -------- Start


  • Kopano

    Hi @Trapulo,

    the gap between log on pastebin and the last part you posted is about 9 minutes, but the runtime of the request in your last post is 3,42 seconds. Does it mean that there were a lot of sync requests for contacts which you left out?

    My guess now would be that your device is not able process some of the contacts and therefore starts the sync from 0.

    Manfred



  • @Manfred Yes, it downloads more that 800 contacts, it takes some time. On the phone contacts count grows during this time.

    I also tried to clear the server archive, and leave only one contact. Same behavior.


  • Kopano

    Hi @Trapulo,

    can you post the WBXML log with one contact sync and not remove anything from it?

    Manfred



  • Hi everyone!

    I think I may have a very similar problem, but I posted it into another thread :https://forum.kopano.io/topic/3010/unable-to-keep-appointments-after-foldersync

    Any ideas for me, @Manfred, @fbartels ?

    BB



  • @Manfred sorry for delay

    Here there is the log.
    I’ve deleted all contact but one from the server. Then I’ve re-created the account on the phone. It has synchronized (more than one time I see) and this is the log. At the end, I’ve 0 contacts on the phone :(

    https://pastebin.com/DaHj0dPb

    thanks


  • Kopano

    Hi @Trapulo,

    I don’t see any obvious problems in the last WBXML log you’ve posted. Maybe the device isn’t able to process the ServerEntryId or Categories. Or you have something really different in the data you replaced wit "x"s.

    Another thing you could try would be to debug the device with the adb.

    Manfred



  • I see you have log messages to say you have created Fake Inbox and Fake Deleted Items folders. I wonder if the client also requires an Outbox folder. I know that for the zimbra backend we had to add a Fake Outbox folder years ago.

    Can you try adding another folder name “Outbox” and folder type 6 (SYNC_FOLDER_TYPE_OUTBOX)

    You might also need a “Sent” folder - which is type 5 (SYNC_FOLDER_TYPE_SENTMAIL)


Log in to reply