Kopano's data is not synchronised when using BackendCombined for Kopano + Dovecot



  • Dear Developers

    Thank you for developing Z-Push very much!

    The undefined GetImpersonatedUser() issue when using BackendKopano under BackendCombined has been solved, refer the following post.
    https://forum.kopano.io/topic/2598/undefined-getimpersonateduser-if-backendcombined-for-kopano-dovecot

    Then I am seeing different issue.

    [Symptom]
    In /etc/z-push/z-push.conf.php,
    (1)Kopano’s Mails, Contacts, Calendars, Tasks, Notes are syncronised correctly if BACKEND_PROVIDER is BackendKopano
    (2)Dovecot’s Mails are syncronised correctly if BACKEND_PROVIDER is BackendImap
    (3)Kopano’s Contacts, Calendars, Tasks, Notes are not synchronised if BACKEND_PROVIDER is BackendCombined for BackendKopano + BackendImap

    [Environment]
    After ‘yum -y update’ in the following environment
    CentOS 7
    Apache 2.4.6-89.el7.centos.1
    PHP 7.3.8-1.el7.remi
    Kopano core-8.7.82.61 + small fix for the undefined GetImpersonatedUser() issue
    Z-Push 2.5.0+0-117.1
    Dovecot 1:2.2.36-3.el7

    [Details]
    To make the investigation simpler, only the Kopano’s ‘Notes’ was enabled and tested in iPhone’s Settings - Passwords&Accounts.

    (1)
    define(‘BACKEND_PROVIDER’, ‘BackendKopano’); in /etc/z-push/z-push.conf.php
    Kopano’s Notes can be syncronised correctly.
    IMG_0314.PNG

    (2)
    define(‘BACKEND_PROVIDER’, ‘BackendImap’); in /etc/z-push/z-push.conf.php
    ‘Notes’ is not supported by BackendImap.

    (3)
    /etc/z-push/z-push.conf.php
    define(‘BACKEND_PROVIDER’, ‘BackendCombined’);
    Kopano’s Notes is not synchronised.
    IMG_0315.PNG

    Z-push log for Notes

    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] -------- Start
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] cmd='FolderSync' devType='iPhone' devId='smirvfp8kt1qj93696qj4r0d94' getUser='hoho' from='192.168.150.128' version='2.5.0+0' method='POST'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] Used timezone 'UTC'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] Including backend file: '/usr/share/z-push/backend/kopano/kopano.php'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] BackendKopano using PHP-MAPI version: 8.7.82.59.aadca417e - PHP version: 7.3.8
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] Combined 1 backends loaded.
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] Request::ProcessHeaders() ASVersion: 14.0
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ZPush::CommandNeedsProvisioning(9): true
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] FileStateMachine->GetState() read '3150' bytes from file: '/var/lib/z-push/4/9/smirvfp8kt1qj93696qj4r0d94-devicedata'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ASDevice data loaded for user: 'hoho'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] TopCollector(): Initialized mutexid Resource id #9 and memid Resource id #10.
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] LoopDetection(): Initialized mutexid Resource id #11 and memid Resource id #12.
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] LoopDetection initialised with IPC provider 'IpcSharedMemoryProvider' with type '1337'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ZPush::HierarchyCommand(9): true
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] DeviceManager->ProvisioningRequired('708379090') saved device key '708379090': false
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] DeviceManager->getPolicyName(): determined policy name: 'default'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] DeviceManager->getProvisioningPolicies(): loaded 'default' policy.
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ZPush::CommandNeedsAuthentication(9): true
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] Combined->Logon('hoho', '',***))
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] KopanoBackend->Logon(): Trying to authenticate user 'hoho'..
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] KopanoBackend->openMessageStore('hoho'): Found 'DEFAULT' store: 'Resource id #15'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] KopanoBackend->Logon(): User 'hoho' is authenticated
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] Store supports properties containing Unicode characters.
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] Combined->Logon() success
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ZPush::CommandNeedsPlainInput(9): false
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ZPush::GetSupportedProtocolVersions(): 12.0,12.1,14.0
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ZPush::GetSupportedCommands(): Sync,SendMail,SmartForward,SmartReply,GetAttachment,GetHierarchy,CreateCollection,DeleteCollection,MoveCollection,FolderSync,FolderCreate,FolderDelete,FolderUpdate,MoveItems,GetItemEstimate,MeetingResponse,ResolveRecipients,ValidateCert,Provision,Search,Ping,Notify,ItemOperations,Settings
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] FileStateMachine->GetState() read '379' bytes from file: '/var/lib/z-push/4/9/smirvfp8kt1qj93696qj4r0d94-a9a7fe3b-041d-4203-9002-6b08c82bb0b2-fd'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] SyncParameters->UseCPO('DEFAULT')
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 0
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] Combined->Setup('', 'false', '', 'false')
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] Combined->Setup() success
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] Combined->GetExporter('')
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ExportChangesCombined constructed
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ExportChangesCombined->Config(...)
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] UKUS exporter.php Request::GetImpersonatedUser ##
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ExportChangesICS->Config() initialized with state: 0x0000000000000000
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ExportChangesCombined->Config() success
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ExportChangesCombined->InitializeExporter(...)
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->__construct('z',...)
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ExportChangesICS->InitializeExporter() successfully. 15 changes ready to sync for 'hierarchy'.
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ExportChangesCombined->InitializeExporter(...) success
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ExportChangesCombined->GetChangeCount()
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ExportChangesCombined->GetChangeCount() success
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ExportChangesCombined->Synchronize()
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] MAPIProvider->GetStoreProps(): Getting store properties.
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 09:55:25 localhost z-push/core[14249]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/U9675c'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): found backendid in contentdata but with different folder type. Lookup 'U' - synchronized id 'U9675c'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] MAPIProvider->getInboxProps(): Getting inbox properties.
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('U9675c')
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/U9675c displayname: Inbox
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 09:55:25 localhost z-push/core[14249]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/U57fb1'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): found backendid in contentdata but with different folder type. Lookup 'U' - synchronized id 'U57fb1'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('U57fb1')
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/U57fb1 displayname: Outbox
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 09:55:25 localhost z-push/core[14249]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/U95862'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): found backendid in contentdata but with different folder type. Lookup 'U' - synchronized id 'U95862'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('U95862')
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/U95862 displayname: Deleted Items
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 09:55:25 localhost z-push/core[14249]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/Uc6446'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): found backendid in contentdata but with different folder type. Lookup 'U' - synchronized id 'Uc6446'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('Uc6446')
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/Uc6446 displayname: Sent Items
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 09:55:25 localhost z-push/core[14249]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/Ucdea9'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): found backendid in contentdata but with different folder type. Lookup 'U' - synchronized id 'Ucdea9'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('Ucdea9')
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/Ucdea9 displayname: Contacts
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 09:55:25 localhost z-push/core[14249]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/Ue4d18'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): found backendid in contentdata but with different folder type. Lookup 'U' - synchronized id 'Ue4d18'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('Ue4d18')
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/Ue4d18 displayname: Calendar
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 09:55:25 localhost z-push/core[14249]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/U255f5'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): found backendid in contentdata but with different folder type. Lookup 'U' - synchronized id 'U255f5'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('U255f5')
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/U255f5 displayname: Drafts
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 09:55:25 localhost z-push/core[14249]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/Ubcbd3'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): found backendid in contentdata but with different folder type. Lookup 'U' - synchronized id 'Ubcbd3'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('Ubcbd3')
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/Ubcbd3 displayname: Journal
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 09:55:25 localhost z-push/core[14249]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/Ub7136'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): found backendid in contentdata but with different folder type. Lookup 'U' - synchronized id 'Ub7136'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('Ub7136')
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/Ub7136 displayname: Notes
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 09:55:25 localhost z-push/core[14249]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/U769db'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): found backendid in contentdata but with different folder type. Lookup 'U' - synchronized id 'U769db'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('U769db')
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/U769db displayname: Tasks
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 09:55:25 localhost z-push/core[14249]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/Uef7fd'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): found backendid in contentdata but with different folder type. Lookup 'U' - synchronized id 'Uef7fd'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('Uef7fd')
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/Uef7fd displayname: Junk E-mail
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] MAPIProvider->GetFolder(): folder 'RSS Feeds' should not be synchronized
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] MAPIProvider->GetFolder(): invalid folder 'Conversation Action Settings' as it is a hidden folder (PR_ATTR_HIDDEN)
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] MAPIProvider->GetFolder(): invalid folder 'Quick Step Settings' as it is a hidden folder (PR_ATTR_HIDDEN)
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] MAPIProvider->GetFolder(): folder 'Suggested Contacts' should not be synchronized
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ExportChangesCombined->Synchronize() success
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ExportChangesCombined->GetState()
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ExportChangesCombined->GetState() success
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] StateManager::UnLinkState(''): saved state 'a9a7fe3b-041d-4203-9002-6b08c82bb0b2' will be deleted.
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] FileStateMachine->CleanStates(): Deleting file: '/var/lib/z-push/4/9/smirvfp8kt1qj93696qj4r0d94-a9a7fe3b-041d-4203-9002-6b08c82bb0b2-1'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] FileStateMachine->CleanStates(): Deleting file: '/var/lib/z-push/4/9/smirvfp8kt1qj93696qj4r0d94-a9a7fe3b-041d-4203-9002-6b08c82bb0b2-fd'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] FileStateMachine->CleanStates(): Deleting file: '/var/lib/z-push/4/9/smirvfp8kt1qj93696qj4r0d94-a9a7fe3b-041d-4203-9002-6b08c82bb0b2-hc-1'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] StateManager::linkState(#ASDevice, 'b8f05047-3470-44b5-867e-036e86bce6ad','HierarchyCache'): linked to uuid 'b8f05047-3470-44b5-867e-036e86bce6ad'.
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 11
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] FileStateMachine->SetState() written 2844 bytes on file: '/var/lib/z-push/4/9/smirvfp8kt1qj93696qj4r0d94-b8f05047-3470-44b5-867e-036e86bce6ad-hc-1'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] FileStateMachine->SetState() written 29 bytes on file: '/var/lib/z-push/4/9/smirvfp8kt1qj93696qj4r0d94-b8f05047-3470-44b5-867e-036e86bce6ad-1'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] SyncCollections::InvalidatePingableFlags(): Invalidating now
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] FileStateMachine->SetState() written 379 bytes on file: '/var/lib/z-push/4/9/smirvfp8kt1qj93696qj4r0d94-b8f05047-3470-44b5-867e-036e86bce6ad-fd'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] WBXMLEncoder->endTag() WBXML output completed
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] Special header: MS-ASProtocolVersions: 12.0,12.1,14.0
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] Special header: MS-ASProtocolCommands: Sync,SendMail,SmartForward,SmartReply,GetAttachment,GetHierarchy,CreateCollection,DeleteCollection,MoveCollection,FolderSync,FolderCreate,FolderDelete,FolderUpdate,MoveItems,GetItemEstimate,MeetingResponse,ResolveRecipients,ValidateCert,Provision,Search,Ping,Notify,ItemOperations,Settings
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] Combined->Logoff()
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] Combined->Logoff() success
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] DeviceManager->Save(): Device data changed
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] FileStateMachine->SetState() written 3150 bytes on file: '/var/lib/z-push/4/9/smirvfp8kt1qj93696qj4r0d94-devicedata'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] DeviceManager->Save(): Device data saved
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] LoopDetection->ProcessLoopDetectionTerminate()
    Aug  6 09:55:25 localhost z-push/core[14249]: [INFO] [hoho] cmd='FolderSync' memory='2.15 MiB/4.00 MiB' time='0.48s' devType='iPhone' devId='smirvfp8kt1qj93696qj4r0d94' getUser='hoho' from='192.168.150.128' idle='0s' version='2.5.0+0' method='POST' httpcode='200'
    Aug  6 09:55:25 localhost z-push/core[14249]: [DEBUG] [hoho] -------- End
    

    /etc/z-push/combined.conf.php

    <?php
    /***********************************************
    * File      :   backend/combined/config.php
    * Project   :   Z-Push
    * Descr     :   configuration file for the
    *               combined backend.
    *
    * Created   :   29.11.2010
    *
    * Copyright 2007 - 2016 Zarafa Deutschland GmbH
    *
    * This program is free software: you can redistribute it and/or modify
    * it under the terms of the GNU Affero General Public License, version 3,
    * as published by the Free Software Foundation.
    *
    * This program is distributed in the hope that it will be useful,
    * but WITHOUT ANY WARRANTY; without even the implied warranty of
    * MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the
    * GNU Affero General Public License for more details.
    *
    * You should have received a copy of the GNU Affero General Public License
    * along with this program.  If not, see <http://www.gnu.org/licenses/>.
    *
    * Consult LICENSE file for details
    ************************************************/
    
    class BackendCombinedConfig {
    
        // *************************
        //  BackendCombined settings
        // *************************
        /**
         * Returns the configuration of the combined backend
         *
         * @access public
         * @return array
         *
         */
        public static function GetBackendCombinedConfig() {
            //use a function for it because php does not allow
            //assigning variables to the class members (expecting T_STRING)
            return array(
                //the order in which the backends are loaded.
                //login only succeeds if all backend return true on login
                //sending mail: the mail is sent with first backend that is able to send the mail
                'backends' => array(
                    'i' => array(
                        'name' => 'BackendIMAP',
                    ),
                    'z' => array(
                        'name' => 'BackendKopano',
                    ),
                    'm' => array(
                        'name' => 'BackendMaildir',
                    ),
                    'v' => array(
                        'name' => 'BackendVCardDir',
                    ),
                    'l' => array(
                        'name' => 'BackendLDAP',
                    ),
                    'd' => array(
                        'name' => 'BackendCardDAV',
                    ),
                    'c' => array(
                        'name' => 'BackendCalDAV',
                    ),
                ),
                'delimiter' => '/',
                //force one type of folder to one backend
                //it must match one of the above defined backends
                'folderbackend' => array(
                    SYNC_FOLDER_TYPE_INBOX => 'i',
                    SYNC_FOLDER_TYPE_DRAFTS => 'i',
                    SYNC_FOLDER_TYPE_WASTEBASKET => 'i',
                    SYNC_FOLDER_TYPE_SENTMAIL => 'i',
                    SYNC_FOLDER_TYPE_OUTBOX => 'i',
                    SYNC_FOLDER_TYPE_TASK => 'z',
                    SYNC_FOLDER_TYPE_APPOINTMENT => 'z',
                    SYNC_FOLDER_TYPE_CONTACT => 'z',
                    SYNC_FOLDER_TYPE_NOTE => 'z',
                    SYNC_FOLDER_TYPE_JOURNAL => 'z',
                    SYNC_FOLDER_TYPE_OTHER => 'z',
                    SYNC_FOLDER_TYPE_USER_MAIL => 'i',
                    SYNC_FOLDER_TYPE_USER_APPOINTMENT => 'z',
                    SYNC_FOLDER_TYPE_USER_CONTACT => 'z',
                    SYNC_FOLDER_TYPE_USER_TASK => 'z',
                    SYNC_FOLDER_TYPE_USER_JOURNAL => 'z',
                    SYNC_FOLDER_TYPE_USER_NOTE => 'z',
                    SYNC_FOLDER_TYPE_UNKNOWN => 'z',
                ),
                //creating a new folder in the root folder should create a folder in one backend
                'rootcreatefolderbackend' => 'z',
            );
        }
    }
    
    

    /etc/z-push/kopano.conf.php

    <?php
    /***********************************************
    * File      :   config.php
    * Project   :   Z-Push
    * Descr     :   Kopano backend configuration file
    *
    * Created   :   27.11.2012
    *
    * Copyright 2007 - 2016 Zarafa Deutschland GmbH
    *
    * This program is free software: you can redistribute it and/or modify
    * it under the terms of the GNU Affero General Public License, version 3,
    * as published by the Free Software Foundation.
    *
    * This program is distributed in the hope that it will be useful,
    * but WITHOUT ANY WARRANTY; without even the implied warranty of
    * MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the
    * GNU Affero General Public License for more details.
    *
    * You should have received a copy of the GNU Affero General Public License
    * along with this program.  If not, see <http://www.gnu.org/licenses/>.
    *
    * Consult LICENSE file for details
    ************************************************/
    
    // ************************
    //  BackendKopano settings
    // ************************
    
    // Defines the server to which we want to connect.
    //
    // Depending on your setup, it might be advisable to change the lines below to one defined with your
    // default socket location.
    // Normally "default:" points to the default setting ("file:///var/run/kopano/server.sock")
    // Examples: define("MAPI_SERVER", "default:");
    //           define("MAPI_SERVER", "http://localhost:236/kopano");
    //           define("MAPI_SERVER", "https://localhost:237/kopano");
    //           define("MAPI_SERVER", "file:///var/run/kopano/server.sock");
    // If you are using ZCP >= 7.2.0, set it to the zarafa location, e.g.
    //           define("MAPI_SERVER", "http://localhost:236/zarafa");
    //           define("MAPI_SERVER", "https://localhost:237/zarafa");
    //           define("MAPI_SERVER", "file:///var/run/zarafad/server.sock");
    // For ZCP versions prior to 7.2.0 the socket location is different (http(s) sockets are the same):
    //           define("MAPI_SERVER", "file:///var/run/zarafa");
    
    define('MAPI_SERVER', 'default:');
    
    // Read-Only shared folders
    //   When trying to write a change on a read-only folder this data is dropped and replaced on the device of the user.
    //   Enabling the option below, sends an email to the user notifying that this happened (default enabled).
    //   If this is disabled, the data will be dropped silently and will be lost.
    //   The template of the email sent can be customized here. The placeholders can also be used in the subject.
    define('READ_ONLY_NOTIFY_LOST_DATA', true);
    // String to mark the data changed by the user (that he is trying to save)
    define('READ_ONLY_NOTIFY_YOURDATA', 'Your data');
    // Email template to be sent to the user
    define('READ_ONLY_NOTIFY_SUBJECT', "Z-Push: Writing operation not permitted - data reset");
    define('READ_ONLY_NOTIFY_BODY', <<<END
    Dear **USERFULLNAME**,
    
    on **DATE** at **TIME** you've tried to save a data in the folder '**FOLDERNAME**' on your device '**MOBILETYPE**' ID: '**MOBILEDEVICEID**'.
    
    This operation was not successful, as you lack write access to this folder.
    Your data has been dropped and replaced with the original data on your device to ensure data integrity.
    
    Below is a copy of the data you tried to save. If you want your changes to be stored permanently you should forward this email to a person with write access to this folder asking to perform these changes again.
    **DIFFERENCES**
    
    If you have questions about this email, please contact your e-mail administrator.
    
    Sincerely,
    Your Z-Push system
    END
             );
    // Format of the **DATE** and **TIME** placeholders - more information on formats, see http://php.net/manual/en/function.strftime.php
    define('READ_ONLY_NOTIFY_DATE_FORMAT', "%d.%m.%Y");
    define('READ_ONLY_NOTIFY_TIME_FORMAT', "%H:%M:%S");
    
    // Comma separated list of folder ids as string for which the notification emails of the changes in read-only folders shouldn't be sent.
    // E.g. define('READ_ONLY_NONOTIFY', '1, 2, 3, 4');
    // When configuring $additionalFolders it is possible to use DeviceManager::FLD_FLAGS_NOREADONLYNOTIFY in the flags bitmask
    // in order to prevent the notifications as well.
    define('READ_ONLY_NONOTIFY', '');
    
    

  • Kopano

    Hi ukus,

    I’ve created https://jira.z-hub.io/browse/ZP-1517 to address this issue.

    In the meantime you can workaround this by changing

    $origin = substr($folderid, 0, 1);
    

    to

    $origin = substr($folderid, 2, 1);
    

    in [z-push-folder]/lib/utils/utils.php at line 1102.

    Be aware that this only works if you’re using BackendCombined! If you switch to BackendKopano, you have to reverse the change.

    Manfred



  • Dear Manfred,

    Thank you for your response.
    I tried substr($folderid, 2, 1), but it did not work fine, it left the logs

    Aug 6 17:14:08 localhost z-push/core[6849]: [INFO] [hoho] StatusException: HandleSync() could not get an exporter for folder id z/z/Ub7136/31511f0adb1145b48f692f935a383820100000000000 - code: 12 - file: /usr/share/z-push/lib/request/sync.php:873

    and also

    Aug 6 17:14:11 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id ‘z/z/U9675c’

    The followings are the Z-push log after changed to $origin = substr($folderid, 2, 1); As there wwas 32KBytes limitation to post here, I picked up one block in the log, it means the followings are not whole log I am afraid.

    Aug  6 17:14:04 localhost z-push/core[6849]: [DEBUG] [hoho] -------- Start
    Aug  6 17:14:04 localhost z-push/core[6849]: [DEBUG] [hoho] cmd='FolderSync' devType='iPhone' devId='smirvfp8kt1qj93696qj4r0d94' getUser='hoho' from='192.168.150.128' version='2.5.0+0' method='POST'
    Aug  6 17:14:04 localhost z-push/core[6849]: [DEBUG] [hoho] Used timezone 'UTC'
    Aug  6 17:14:04 localhost z-push/core[6849]: [DEBUG] [hoho] Including backend file: '/usr/share/z-push/backend/kopano/kopano.php'
    Aug  6 17:14:04 localhost z-push/core[6849]: [DEBUG] [hoho] BackendKopano using PHP-MAPI version: 8.7.82.59.aadca417e - PHP version: 7.3.8
    Aug  6 17:14:04 localhost z-push/core[6849]: [DEBUG] [hoho] Combined 1 backends loaded.
    Aug  6 17:14:04 localhost z-push/core[6849]: [DEBUG] [hoho] Request::ProcessHeaders() ASVersion: 14.0
    Aug  6 17:14:04 localhost z-push/core[6849]: [DEBUG] [hoho] ZPush::CommandNeedsProvisioning(9): true
    Aug  6 17:14:04 localhost z-push/core[6849]: [INFO] [hoho] ProvisioningRequiredException: Retry after sending a PROVISION command - code: 0 - file: /usr/share/z-push/index.php:82
    Aug  6 17:14:04 localhost z-push/core[6849]: [INFO] [hoho] User-agent: 'Apple-iPhone7C1/1606.203'
    Aug  6 17:14:04 localhost z-push/core[6849]: [FATAL] [hoho] Exception: (ProvisioningRequiredException) - Retry after sending a PROVISION command
    Aug  6 17:14:04 localhost z-push/core[6849]: [DEBUG] [hoho] TopCollector(): Initialized mutexid Resource id #8 and memid Resource id #9.
    Aug  6 17:14:04 localhost z-push/core[6849]: [DEBUG] [hoho] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20'
    Aug  6 17:14:04 localhost z-push/core[6849]: [INFO] [hoho] cmd='FolderSync' memory='1.68 MiB/2.00 MiB' time='0.01s' devType='iPhone' devId='smirvfp8kt1qj93696qj4r0d94' getUser='hoho' from='192.168.150.128' idle='0s' version='2.5.0+0' method='POST' httpcode='449'
    Aug  6 17:14:04 localhost z-push/core[6849]: [DEBUG] [hoho] -------- End
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] -------- Start
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] cmd='FolderSync' devType='iPhone' devId='smirvfp8kt1qj93696qj4r0d94' getUser='hoho' from='192.168.150.128' version='2.5.0+0' method='POST'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] Used timezone 'UTC'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] Including backend file: '/usr/share/z-push/backend/kopano/kopano.php'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] BackendKopano using PHP-MAPI version: 8.7.82.59.aadca417e - PHP version: 7.3.8
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] Combined 1 backends loaded.
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] Request::ProcessHeaders() ASVersion: 14.0
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ZPush::CommandNeedsProvisioning(9): true
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] FileStateMachine->GetState() read '608' bytes from file: '/var/lib/z-push/4/9/smirvfp8kt1qj93696qj4r0d94-devicedata'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ASDevice data loaded for user: 'hoho'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] TopCollector(): Initialized mutexid Resource id #9 and memid Resource id #10.
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] LoopDetection(): Initialized mutexid Resource id #11 and memid Resource id #12.
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] LoopDetection initialised with IPC provider 'IpcSharedMemoryProvider' with type '1337'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ZPush::HierarchyCommand(9): true
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] DeviceManager->ProvisioningRequired('333124254') saved device key '333124254': false
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] DeviceManager->getPolicyName(): determined policy name: 'default'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] DeviceManager->getProvisioningPolicies(): loaded 'default' policy.
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ZPush::CommandNeedsAuthentication(9): true
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] Combined->Logon('hoho', '',***))
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] KopanoBackend->Logon(): Trying to authenticate user 'hoho'..
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] KopanoBackend->openMessageStore('hoho'): Found 'DEFAULT' store: 'Resource id #15'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] KopanoBackend->Logon(): User 'hoho' is authenticated
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] Store supports properties containing Unicode characters.
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] Combined->Logon() success
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ZPush::CommandNeedsPlainInput(9): false
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ZPush::GetSupportedProtocolVersions(): 12.0,12.1,14.0
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ZPush::GetSupportedCommands(): Sync,SendMail,SmartForward,SmartReply,GetAttachment,GetHierarchy,CreateCollection,DeleteCollection,MoveCollection,FolderSync,FolderCreate,FolderDelete,FolderUpdate,MoveItems,GetItemEstimate,MeetingResponse,ResolveRecipients,ValidateCert,Provision,Search,Ping,Notify,ItemOperations,Settings
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 0
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] Combined->Setup('', 'false', '', 'false')
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] Combined->Setup() success
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] Combined->GetExporter('')
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ExportChangesCombined constructed
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ExportChangesCombined->Config(...)
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] UKUS exporter.php Request::GetImpersonatedUser ##
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ExportChangesICS->Config() initialized with state: 0x0000000000000000
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ExportChangesCombined->Config() success
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ExportChangesCombined->InitializeExporter(...)
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->__construct('z',...)
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ExportChangesICS->InitializeExporter() successfully. 15 changes ready to sync for 'hierarchy'.
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ExportChangesCombined->InitializeExporter(...) success
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ExportChangesCombined->GetChangeCount()
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ExportChangesCombined->GetChangeCount() success
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ExportChangesCombined->Synchronize()
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] MAPIProvider->GetStoreProps(): Getting store properties.
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): generated new folderid 'U9675c' for backend-folderid '31511f0adb1145b48f692f935a383820080000000000'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] MAPIProvider->getInboxProps(): Getting inbox properties.
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('U9675c')
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/U9675c displayname: Inbox
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): generated new folderid 'U57fb1' for backend-folderid '31511f0adb1145b48f692f935a383820090000000000'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('U57fb1')
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/U57fb1 displayname: Outbox
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): generated new folderid 'U95862' for backend-folderid '31511f0adb1145b48f692f935a3838200a0000000000'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('U95862')
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/U95862 displayname: Deleted Items
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): generated new folderid 'Uc6446' for backend-folderid '31511f0adb1145b48f692f935a3838200b0000000000'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('Uc6446')
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/Uc6446 displayname: Sent Items
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): generated new folderid 'Ucdea9' for backend-folderid '31511f0adb1145b48f692f935a3838200c0000000000'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('Ucdea9')
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/Ucdea9 displayname: Contacts
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): generated new folderid 'Ue4d18' for backend-folderid '31511f0adb1145b48f692f935a3838200d0000000000'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('Ue4d18')
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/Ue4d18 displayname: Calendar
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): generated new folderid 'U255f5' for backend-folderid '31511f0adb1145b48f692f935a3838200e0000000000'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('U255f5')
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/U255f5 displayname: Drafts
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): generated new folderid 'Ubcbd3' for backend-folderid '31511f0adb1145b48f692f935a3838200f0000000000'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('Ubcbd3')
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/Ubcbd3 displayname: Journal
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): generated new folderid 'Ub7136' for backend-folderid '31511f0adb1145b48f692f935a383820100000000000'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('Ub7136')
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/Ub7136 displayname: Notes
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): generated new folderid 'U769db' for backend-folderid '31511f0adb1145b48f692f935a383820110000000000'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('U769db')
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/U769db displayname: Tasks
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] UKUS mapiprovider.php Request::GetImpersonatedUser ##
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ASDevice->GetFolderIdForBackendId(): generated new folderid 'Uef7fd' for backend-folderid '31511f0adb1145b48f692f935a383820120000000000'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange('Uef7fd')
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ImportHierarchyChangesCombinedWrap->ImportFolderChange() success
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] HierarchyCache: AddFolder() serverid: z/Uef7fd displayname: Junk E-mail
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] MAPIProvider->GetFolder(): folder 'RSS Feeds' should not be synchronized
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] MAPIProvider->GetFolder(): invalid folder 'Conversation Action Settings' as it is a hidden folder (PR_ATTR_HIDDEN)
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] MAPIProvider->GetFolder(): invalid folder 'Quick Step Settings' as it is a hidden folder (PR_ATTR_HIDDEN)
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] MAPIProvider->GetFolder(): folder 'Suggested Contacts' should not be synchronized
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ExportChangesCombined->Synchronize() success
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ExportChangesCombined->GetState()
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ExportChangesCombined->GetState() success
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] StateManager::linkState(#ASDevice, '8343092e-221d-4681-be9d-cbcc565ee8fe','HierarchyCache'): linked to uuid '8343092e-221d-4681-be9d-cbcc565ee8fe'.
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 11
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] FileStateMachine->SetState() written 2844 bytes on file: '/var/lib/z-push/4/9/smirvfp8kt1qj93696qj4r0d94-8343092e-221d-4681-be9d-cbcc565ee8fe-hc-1'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] FileStateMachine->SetState() written 29 bytes on file: '/var/lib/z-push/4/9/smirvfp8kt1qj93696qj4r0d94-8343092e-221d-4681-be9d-cbcc565ee8fe-1'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] SyncCollections::InvalidatePingableFlags(): Invalidating now
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] FileStateMachine->SetState() written 379 bytes on file: '/var/lib/z-push/4/9/smirvfp8kt1qj93696qj4r0d94-8343092e-221d-4681-be9d-cbcc565ee8fe-fd'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] WBXMLEncoder->endTag() WBXML output completed
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] Special header: MS-ASProtocolVersions: 12.0,12.1,14.0
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] Special header: MS-ASProtocolCommands: Sync,SendMail,SmartForward,SmartReply,GetAttachment,GetHierarchy,CreateCollection,DeleteCollection,MoveCollection,FolderSync,FolderCreate,FolderDelete,FolderUpdate,MoveItems,GetItemEstimate,MeetingResponse,ResolveRecipients,ValidateCert,Provision,Search,Ping,Notify,ItemOperations,Settings
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] Combined->Logoff()
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] Combined->Logoff() success
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] DeviceManager->Save(): Device data changed
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] FileStateMachine->SetState() written 1639 bytes on file: '/var/lib/z-push/4/9/smirvfp8kt1qj93696qj4r0d94-devicedata'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] DeviceManager->Save(): Device data saved
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] LoopDetection->ProcessLoopDetectionTerminate()
    Aug  6 17:14:05 localhost z-push/core[6849]: [INFO] [hoho] cmd='FolderSync' memory='3.57 MiB/6.00 MiB' time='0.19s' devType='iPhone' devId='smirvfp8kt1qj93696qj4r0d94' getUser='hoho' from='192.168.150.128' idle='0s' version='2.5.0+0' method='POST' httpcode='200'
    Aug  6 17:14:05 localhost z-push/core[6849]: [DEBUG] [hoho] -------- End
    Aug  6 17:14:06 localhost z-push/core[6849]: [INFO] [hoho] cmd='FolderSync' memory='2.02 MiB/2.00 MiB' time='0.02s' devType='iPhone' devId='smirvfp8kt1qj93696qj4r0d94' getUser='hoho' from='192.168.150.128' idle='0s' version='2.5.0+0' method='POST' httpcode='200'
    Aug  6 17:14:08 localhost z-push/core[6849]: [INFO] [hoho] StatusException: HandleSync() could not get an exporter for folder id z/z/Ub7136/31511f0adb1145b48f692f935a383820100000000000 - code: 12 - file: /usr/share/z-push/lib/request/sync.php:873
    Aug  6 17:14:08 localhost z-push/core[6849]: [INFO] [hoho] cmd='Sync' memory='2.03 MiB/2.00 MiB' time='0.01s' devType='iPhone' devId='smirvfp8kt1qj93696qj4r0d94' getUser='hoho' from='192.168.150.128' idle='0s' version='2.5.0+0' method='POST' httpcode='200'
    Aug  6 17:14:08 localhost z-push/core[6849]: [INFO] [hoho] cmd='FolderSync' memory='2.02 MiB/2.00 MiB' time='0.02s' devType='iPhone' devId='smirvfp8kt1qj93696qj4r0d94' getUser='hoho' from='192.168.150.128' idle='0s' version='2.5.0+0' method='POST' httpcode='200'
    Aug  6 17:14:10 localhost z-push/core[6849]: [INFO] [hoho] StatusException: HandleSync() could not get an exporter for folder id z/z/Ub7136/31511f0adb1145b48f692f935a383820100000000000 - code: 12 - file: /usr/share/z-push/lib/request/sync.php:873
    Aug  6 17:14:10 localhost z-push/core[6849]: [INFO] [hoho] cmd='Sync' memory='2.03 MiB/2.00 MiB' time='0.01s' devType='iPhone' devId='smirvfp8kt1qj93696qj4r0d94' getUser='hoho' from='192.168.150.128' idle='0s' version='2.5.0+0' method='POST' httpcode='200'
    Aug  6 17:14:11 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/U9675c'
    Aug  6 17:14:11 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/U57fb1'
    Aug  6 17:14:11 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/U95862'
    Aug  6 17:14:11 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/Uc6446'
    Aug  6 17:14:11 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/Ucdea9'
    Aug  6 17:14:11 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/Ue4d18'
    Aug  6 17:14:11 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/U255f5'
    Aug  6 17:14:11 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/Ubcbd3'
    Aug  6 17:14:11 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/Ub7136'
    Aug  6 17:14:11 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/U769db'
    Aug  6 17:14:11 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/Uef7fd'
    Aug  6 17:14:11 localhost z-push/core[6849]: [INFO] [hoho] cmd='FolderSync' memory='2.15 MiB/2.00 MiB' time='0.08s' devType='iPhone' devId='smirvfp8kt1qj93696qj4r0d94' getUser='hoho' from='192.168.150.128' idle='0s' version='2.5.0+0' method='POST' httpcode='200'
    Aug  6 17:14:12 localhost z-push/core[6849]: [INFO] [hoho] cmd='FolderSync' memory='2.02 MiB/2.00 MiB' time='0.02s' devType='iPhone' devId='smirvfp8kt1qj93696qj4r0d94' getUser='hoho' from='192.168.150.128' idle='0s' version='2.5.0+0' method='POST' httpcode='200'
    Aug  6 17:14:14 localhost z-push/core[6849]: [INFO] [hoho] StatusException: HandleSync() could not get an exporter for folder id z/z/Ub7136/31511f0adb1145b48f692f935a383820100000000000 - code: 12 - file: /usr/share/z-push/lib/request/sync.php:873
    Aug  6 17:14:14 localhost z-push/core[6849]: [INFO] [hoho] cmd='Sync' memory='2.03 MiB/2.00 MiB' time='0.01s' devType='iPhone' devId='smirvfp8kt1qj93696qj4r0d94' getUser='hoho' from='192.168.150.128' idle='0s' version='2.5.0+0' method='POST' httpcode='200'
    Aug  6 17:14:15 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/U9675c'
    Aug  6 17:14:15 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/U57fb1'
    Aug  6 17:14:15 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/U95862'
    Aug  6 17:14:15 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/Uc6446'
    Aug  6 17:14:15 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/Ucdea9'
    Aug  6 17:14:15 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/Ue4d18'
    Aug  6 17:14:15 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/U255f5'
    Aug  6 17:14:15 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/Ubcbd3'
    Aug  6 17:14:15 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/Ub7136'
    Aug  6 17:14:15 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/U769db'
    Aug  6 17:14:15 localhost z-push/core[6849]: [WARN] [hoho] Utils->GetFolderOriginFromId(): Unknown folder origin for folder with id 'z/z/Uef7fd'
    Aug  6 17:14:16 localhost z-push/core[6849]: [INFO] [hoho] cmd='FolderSync' memory='2.15 MiB/2.00 MiB' time='0.08s' devType='iPhone' devId='smirvfp8kt1qj93696qj4r0d94' getUser='hoho' from='192.168.150.128' idle='0s' version='2.5.0+0' method='POST' httpcode='200'
    

  • Kopano

    Hi ukus,

    I suggest you to reset all the changes you’ve done to Z-Push yourself and leave only those Sebastian and I mentioned. Then remove ActiveSync account from your device and remove device using z-push-admin so that you start with the fresh installation.

    However I’m also not sure this will work as BackendKopano had too many changes and probably won’t work with BackendCombined properly. Our suggestions were to work around the immediate issues which occur, but apparently they cause other problems to appear. It’s also not something we will investigate in the near future.

    You’d be better of using CardDAV and CalDAV which are known to work well with CombinedBackend for contacts, appointments and tasks. Or you configure two ActiveSync accounts on your mobile - one for IMAP and Kopano for the rest if using Kopano backend only is not an option for you.

    Manfred


Log in to reply