Sync problem with Synology Diskstation CardDAV Server
-
Hi,
I am trying to get Z-Push (version 2.4.4) running with access to the apps MailPlus Server, Calendar, CardDAV Server of a Synology Diskstation (DSM 6.2-23739 Update 2). The backends “IMAP” and “CalDAV” work perfectly. With “CardDAV” I get the following error message in the log:
"BackendCardDAV-> ChangesSinkInitialize - Error doing the initial sync for 'Https://xxxxx.xx.xxxxxxxxxx.de:8443/addressbooks/users/ladmin/addressbook/': Woops, something's gone wrong! The CardDAV server returned the http status code 403. "
The client is an iPhone (iOS 11.4.1). When I set up access from the iPhone directly to the CardDAV server (without Z-Push), all contacts are displayed.
Here the detailed log:09/08/2018 16:36:45 [ 21] [DEBUG] [ladmin] -------- Start 09/08/2018 16:36:45 [ 21] [DEBUG] [ladmin] cmd='Ping' devType='iPhone' devId='b3b6naubi94pv80lq2dmch0nic' getUser='ladmin' from='xx.xx.xx.xx' version='2.4.4+0-0' method='POST' 09/08/2018 16:36:45 [ 21] [DEBUG] [ladmin] Used timezone 'Europe/Berlin' 09/08/2018 16:36:45 [ 21] [DEBUG] [ladmin] Request::ProcessHeaders() ASVersion: 14.0 09/08/2018 16:36:45 [ 21] [DEBUG] [ladmin] ZPush::CommandNeedsProvisioning(18): false 09/08/2018 16:36:45 [ 21] [DEBUG] [ladmin] ZPush::CommandNeedsAuthentication(18): true 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] BackendCardDAV->Logon(): User 'ladmin' is authenticated on 'https://xxxxx.xx.xxxxx-xxxxx.de:8443/addressbooks/users/ladmin/addressbook/' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] BackendCardDAV::discoverAddressbooks() Found addressbook 'https://xxxxx.xx.xxxxx-xxxxx.de:8443/addressbooks/users/ladmin/addressbook/' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] FileStateMachine->GetState() read '2613' bytes from file: '/var/lib/z-push/c/i/b3b6naubi94pv80lq2dmch0nic-devicedata' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] ASDevice data loaded for user: 'ladmin' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] TopCollector(): Initialized mutexid Resource id #20 and memid Resource id #21. 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] LoopDetection(): Initialized mutexid Resource id #24 and memid Resource id #25. 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] LoopDetection initialised with IPC provider 'IpcSharedMemoryProvider' with type '1337' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] ZPush::HierarchyCommand(18): false 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] ZPush::CommandNeedsPlainInput(18): false 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] I <Ping:Ping> 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] FileStateMachine->GetState() read '857' bytes from file: '/var/lib/z-push/c/i/b3b6naubi94pv80lq2dmch0nic-163820ce-c96d-4f41-bd52-950d7c707d75-fd' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] SyncParameters->UseCPO('DEFAULT') 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] ZPush::GetAdditionalSyncFolderStore('contacts'): 'false' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] SyncCollections->AddCollection(): Folder id 'contacts' : ref. PolicyKey '425253863', ref. Lifetime '600', last sync at '1533822859' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] SyncCollections->AddCollection(): Updated reference PolicyKey '425253863', reference Lifetime '600', Last sync at '1533822859' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] SyncParameters->GetLatestSyncKey(): '{163820ce-c96d-4f41-bd52-950d7c707d75}2' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] FileStateMachine->GetState() read '6' bytes from file: '/var/lib/z-push/c/i/b3b6naubi94pv80lq2dmch0nic-163820ce-c96d-4f41-bd52-950d7c707d75-2' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] FileStateMachine->GetState() read '379' bytes from file: '/var/lib/z-push/c/i/b3b6naubi94pv80lq2dmch0nic-4feb40e8-4d5a-47c3-ba7c-1b765275eeb7-fd' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] SyncParameters->UseCPO('DEFAULT') 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] SyncCollections->AddCollection(): Folder id '' : ref. PolicyKey '425253863', ref. Lifetime '10', last sync at '1533825344' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] SyncCollections->AddCollection(): Updated reference PolicyKey '425253863', reference Lifetime '600', Last sync at '1533825344' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] SyncParameters->GetLatestSyncKey(): '{4feb40e8-4d5a-47c3-ba7c-1b765275eeb7}1' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] StateManager->loadHierarchyCache(): 'b3b6naubi94pv80lq2dmch0nic-4feb40e8-4d5a-47c3-ba7c-1b765275eeb7-hc-1' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] FileStateMachine->GetState() read '246' bytes from file: '/var/lib/z-push/c/i/b3b6naubi94pv80lq2dmch0nic-4feb40e8-4d5a-47c3-ba7c-1b765275eeb7-hc-1' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] FileStateMachine->GetState() read '97' bytes from file: '/var/lib/z-push/c/i/b3b6naubi94pv80lq2dmch0nic-4feb40e8-4d5a-47c3-ba7c-1b765275eeb7-1' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] HandlePing(): reference PolicyKey for PING: 425253863 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] HandlePing(): initialization data received 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] I <Ping:LifeTime> 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] I 600 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] I </Ping:LifeTime> 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] I <Ping:Folders> 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] I <Ping:Folder> 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] I <Ping:ServerEntryId> 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] I contacts 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] I </Ping:ServerEntryId> 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] I <Ping:FolderType> 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] I Contacts 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] I </Ping:FolderType> 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] I </Ping:Folder> 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] HandlePing(): using saved sync state for 'Contacts' id 'contacts' 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] I </Ping:Folders> 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] I </Ping:Ping> 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] SyncCollections->SaveCollection(): Data of folder 'contacts' changed 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] FileStateMachine->SetState() written 1 bytes on file: '/var/lib/z-push/c/i/b3b6naubi94pv80lq2dmch0nic-163820ce-c96d-4f41-bd52-950d7c707d75-fd' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] DeviceManager->DoAutomaticASDeviceSaving(): save automatically: false 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] PingTracking(): Initialized mutexid Resource id #72 and memid Resource id #73. 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] PingTracking initialised with IPC provider 'IpcSharedMemoryProvider' with type '2' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] Announce process as PUSH connection 09/08/2018 16:36:46 [ 21] [ INFO] [ladmin] SyncCollections->CheckForChanges(): Waiting for store changes... (lifetime 600 seconds) 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] ZPush::GetAdditionalSyncFolderStore('contacts'): 'false' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] BackendCardDAV->ChangesSinkInitialize(): folderid 'contacts' 09/08/2018 16:36:46 [ 21] [ERROR] [ladmin] BackendCardDAV->ChangesSinkInitialize - Error doing the initial sync for 'https://xxxxx.xx.xxxxx-xxxxx.de:8443/addressbooks/users/ladmin/addressbook/': Woops, something's gone wrong! The CardDAV server returned the http status code 403. 09/08/2018 16:36:46 [ 21] [WARN] [ladmin] /usr/share/z-push/backend/carddav/carddav.php:208 Undefined index: https://xxxxx.xx.xxxxx-xxxxx.de:8443/addressbooks/users/ladmin/addressbook/ (8) 09/08/2018 16:36:46 [ 21] [ INFO] [ladmin] StatusException: Error initializing ChangesSink for folder id contacts/contacts - code: 2 - file: /usr/share/z-push/lib/core/synccollections.php:525 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] LoopDetection->ProcessLoopDetectionAddStatus: 'hierarchy' with status 7 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] O <Ping:Ping> 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] O <Ping:Status> 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] O 7 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] O </Ping:Status> 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] O </Ping:Ping> 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] WBXMLEncoder->endTag() WBXML output completed 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] WBXML-OUT: AwFqAAANRUcDNwABAQ== 09/08/2018 16:36:46 [ 21] [WBXML] [ladmin] WBXML-IN : AwFqAAANRUgDNjAwAAFJSksDY29udGFjdHMAAUwDQ29udGFjdHMAAQEBAQ== 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] BackendCardDAV->Logoff(): disconnected from CARDDAV server 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] LoopDetection->ProcessLoopDetectionTerminate() 09/08/2018 16:36:46 [ 21] [ INFO] [ladmin] cmd='Ping' memory='1.89 MiB/2.00 MiB' time='0.54s' devType='iPhone' devId='b3b6naubi94pv80lq2dmch0nic' getUser='ladmin' from='87.138.86.22' idle='0s' version='2.4.4+0-0' method='POST' httpcode='200' 09/08/2018 16:36:46 [ 21] [DEBUG] [ladmin] -------- End
Does anyone have a hint, what to do?
Thanks
Mark -
(sorry for reactivating this)
Well, did you ever find a solution to this? And could you perhaps enlighten us/me how you did this at all on your Synology DS? I’d very much like to do the same and would only need the mailserver part. As I also use Synology MailPlus as a server this really would make my day …
-
Hi,
Can you tell me how you installed z-push on your synology?