Calendar synchronisation with iOS 11

Since update to iOS 11.0.1 E-Mail, Contacts works but Calendar is not realy synchronized .
Some entries are synchronized to, other in turn not. Respectively late
New created calendar items are not delivered.
Also at the bottom in the Calendar App is a “!” left from Calendar.
In the z-push.log, the following is:
30/09/2017 14:09:32 [ 1738] [ INFO] [rconstap] StatusException: SyncCollections->CheckForChanges(): Timeout forced after 59s from 900s due to other process - code: 3 - file: /usr/share/z-push/lib/core/synccollections.php:579
30/09/2017 14:09:32 [ 1738] [ INFO] [rconstap] cmd=‘Ping’ memory=‘5.49 MiB/2.00 MiB’ time=‘59.44s’ devType=‘iPhone’ devId=‘4diq3icvft5d1bb4m82diduc5k’ getUser=‘rconstap’ from=‘87.122.218.179’ idle=‘59s’ version=‘2.3.8+0-0’ method=‘POST’ httpcode=‘200’

Hello @zwockel ,

I have moved your post into a dedicated topic.

Could you share which backend(s) you are using? Could you create a wbxml of the device attempting to sync the calendar?

ii z-push-backend-imap 2.3.8+0-0 all Z-Push imap backend
ii z-push-backend-kopano 2.3.8+0-0 all Z-Push Kopano backend
ii z-push-common 2.3.8+0-0 all open source implementation of the ActiveSync protocol
ii z-push-config-apache 2.3.8+0-0 all Z-Push apache configuration
ii z-push-kopano-gabsync 2.3.8+0-0 all GAB sync for Kopano

How can i create a wbxml?

i created the wbxml. How can i send it to you?

30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] -------- Start
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] cmd='Sync' devType='iPhone' devId='2hm2u8i68p5t76cbeht0qpikkg' getUser='rconstap' from='87.122.218.179' version='2.3.8+0-0' method='POST'
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] Used timezone 'Europe/Amsterdam'
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] ZPush::GetBackend(): trying autoload backend 'BackendKopano'
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] BackendKopano using PHP-MAPI version: 8.3.4-12 - PHP version: 7.0.22-0ubuntu0.16.04.1
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] Request::ProcessHeaders() ASVersion: 14.0
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] ZPush::CommandNeedsProvisioning(0): true
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] FileStateMachine->GetState() read '78235' bytes from file: '/var/lib/z-push/g/k/2hm2u8i68p5t76cbeht0qpikkg-devicedata'
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] ASDevice data loaded for user: 'rconstap'
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] TopCollector(): Initialized mutexid Resource id #9 and memid Resource id #10.
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20'
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] LoopDetection(): Initialized mutexid Resource id #11 and memid Resource id #12.
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] LoopDetection initialised with IPC provider 'IpcSharedMemoryProvider' with type '1337'
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] ZPush::HierarchyCommand(0): false
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] DeviceManager->ProvisioningRequired('632973763') saved device key '632973763': false
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] DeviceManager->getPolicyName(): determined policy name: 'default'
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] DeviceManager->getProvisioningPolicies(): loaded 'default' policy.
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] ZPush::CommandNeedsAuthentication(0): true
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] KopanoBackend->Logon(): Trying to authenticate user 'rconstap'..
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] KopanoBackend->openMessageStore('rconstap'): Found 'DEFAULT' store: 'Resource id #15'
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] KopanoBackend->Logon(): User 'rconstap' is authenticated
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] Store supports properties containing Unicode characters.
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] ZPush::CommandNeedsPlainInput(0): false
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I  <Synchronize>
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I   <Folders>
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I    <Folder>
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I     <SyncKey>
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I      {7f3ed5f9-92b9-4165-9549-c248b9ac034b}151
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I     </SyncKey>
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I     <FolderId>
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I      Ua1d3e
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I     </FolderId>
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] FileStateMachine->GetState() read '970' bytes from file: '/var/lib/z-push/g/k/2hm2u8i68p5t76cbeht0qpikkg-7f3ed5f9-92b9-4165-9549-c248b9ac034b-fd'
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] SyncParameters->UseCPO('DEFAULT')
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] DeviceManager->GetBackendIdForFolderId(): folderid Ua1d3e => 530f88894db34e14a8fde0045d4a16990301000000000000
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] SyncCollections->AddCollection(): Folder id 'Ua1d3e' : ref. PolicyKey '632973763', ref. Lifetime '600', last sync at '1506782709'
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] SyncCollections->AddCollection(): Updated reference PolicyKey '632973763', reference Lifetime '600', Last sync at '1506782709'
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I     <GetChanges/>
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I     <WindowSize>
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I      25
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I     </WindowSize>
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I     <Options>
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I      <AirSyncBase:BodyPreference>
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] SyncParameters->UseCPO('DEFAULT')
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I       <AirSyncBase:Type>
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I        1
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I       </AirSyncBase:Type>
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I       <AirSyncBase:TruncationSize>
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I        32768
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I       </AirSyncBase:TruncationSize>
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I      </AirSyncBase:BodyPreference>
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I     </Options>
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I    </Folder>
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I   </Folders>
30/09/2017 16:45:14 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] I  </Synchronize>
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] HandleSync(): Start Output
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] ZPush::GetAdditionalSyncFolderStore('530f88894db34e14a8fde0045d4a16990301000000000000'): 'false'
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] KopanoBackend->GetFolderStat() fetched status information of 681 folders for store 'rconstap'
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] SyncParameters->IsExporterRunRequired(): true - current: 1506781662/9782/1/14 - saved: false - expiring: 2017-10-29 11:13:36
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] Sync->loadStates(): loading states for folder 'Ua1d3e'
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] FileStateMachine->CleanStates(): Deleting file: '/var/lib/z-push/g/k/2hm2u8i68p5t76cbeht0qpikkg-7f3ed5f9-92b9-4165-9549-c248b9ac034b-150'
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] FileStateMachine->GetState() read '94523' bytes from file: '/var/lib/z-push/g/k/2hm2u8i68p5t76cbeht0qpikkg-7f3ed5f9-92b9-4165-9549-c248b9ac034b-151'
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] ZPush::GetAdditionalSyncFolderStore('530f88894db34e14a8fde0045d4a16990301000000000000'): 'false'
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] ZPush::GetAdditionalSyncFolderStore('530f88894db34e14a8fde0045d4a16990301000000000000'): 'false'
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] ExportChangesICS->Config(): synching inital data
30/09/2017 16:45:14 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] ExportChangesICS->Config() initialized with state: 0x21e60400000000004e0c00000a6d100016000000530f88894db34e1 <log message with 189077 bytes truncated>
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] ExportChangesICS->InitializeExporter() successfully. 6632 changes ready to sync for '530f88894db34e14a8fde0045d4a16990301000000000000'.
09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] LoopDetection->ProcessLoopDetectionAddStatus: 'Ua1d3e' with status 1
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] Folder type: Calendar
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O  <Synchronize>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O   <Folders>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O    <Folder>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O     <SyncKey>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O     {7f3ed5f9-92b9-4165-9549-c248b9ac034b}152
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O     </SyncKey>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O     <FolderId>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O     Ua1d3e
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O     </FolderId>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O     <Status>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O     1
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O     </Status>
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] LoopDetection->Detect(): folderid:'Ua1d3e' uuid:'7f3ed5f9-92b9-4165-9549-c248b9ac034b' counter:'151' max:'25' queued:'6632'
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O     <MoreAvailable/>
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] PHPWrapper->ImportMessageChange(): Getting message from MAPIProvider, sourcekey: '530f88894db34e14a8fde0045d4a1699389e05000000', parentsourcekey: '530f88894db34e14a8fde0045d4a16990301000000000000', entryid: '00000000706aeeeb7c034000b134ed4b8824266f0100000005000000ff065270667346f392ba418f39d98f5100000000'
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] BodyPreference types: 1
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] GetBodyPreferenceBestMatch: 1
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] bpo: truncation size:'32768', allornone:'0', preview:'0'
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] MAPIStreamWrapper::stream_open(): initialized mapistream: Resource id #116 - streamlength: 12 - HTML-safe-truncate: false
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] TimezoneUtil::GetFullTZ() for Europe/Amsterdam
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] MAPIProvider->getAppointment(): setting ourself as the organizer for an appointment without attendees.
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] ZPush::GetAdditionalSyncFolderStore('530f88894db34e14a8fde0045d4a16990301000000000000'): 'false'
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O     <Perform>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O      <Add>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O       <ServerEntryId>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O       Ua1d3e:530f88894db34e14a8fde0045d4a1699389e05000000
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O       </ServerEntryId>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O       <Data>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        <POOMCAL:Timezone>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        xP///ygARwBNAFQAKwAwADEAOgAwADAAKQAgAEEAbQBzAHQAZQByAGQAYQBtACwAIABCAGUAcgBsAGkAbgAsACAAQgAAAAoAAAAFAAMAAAAAAAAAAAAAACgARwBNAFQAKwAwADEAOgAwADAAKQAgAEEAbQBzAHQAZQByAGQAYQBtACwAIABCAGUAcgBsAGkAbgAsACAAQgAAAAMAAAAFAAIAAAAAAAAAxP///w==
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        </POOMCAL:Timezone>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        <POOMCAL:DtStamp>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        20150202T105311Z
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        </POOMCAL:DtStamp>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        <POOMCAL:StartTime>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        20150202T100000Z
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        </POOMCAL:StartTime>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        <POOMCAL:Subject>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        Christian Heuer anrufen
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        </POOMCAL:Subject>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        <POOMCAL:UID>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        0FFFB046C77F4F58B6B0F10212C73CD60
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        </POOMCAL:UID>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        <POOMCAL:OrganizerName>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        R.Constapel
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        </POOMCAL:OrganizerName>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        <POOMCAL:OrganizerEmail>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        rconstap@hermannbecker.de
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        </POOMCAL:OrganizerEmail>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        <POOMCAL:Location>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        Oventrop
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        </POOMCAL:Location>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        <POOMCAL:EndTime>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        20150202T110000Z
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        </POOMCAL:EndTime>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        <POOMCAL:Sensitivity>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        0
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        </POOMCAL:Sensitivity>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        <POOMCAL:BusyStatus>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        2
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        </POOMCAL:BusyStatus>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        <POOMCAL:AllDayEvent>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        0
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        </POOMCAL:AllDayEvent>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        <POOMCAL:MeetingStatus>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        0
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        </POOMCAL:MeetingStatus>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        <AirSyncBase:Body>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O         <AirSyncBase:Type>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O         1
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O         </AirSyncBase:Type>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O         <AirSyncBase:EstimatedDataSize>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O         12
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O         </AirSyncBase:EstimatedDataSize>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O         <AirSyncBase:Data>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O         <<< written 12 of 12 bytes of plain data >>>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O         </AirSyncBase:Data>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        </AirSyncBase:Body>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        <AirSyncBase:NativeBodyType>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        3
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O        </AirSyncBase:NativeBodyType>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O       </Data>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O      </Add>
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] PHPWrapper->ImportMessageChange(): change for: 'Ua1d3e:530f88894db34e14a8fde0045d4a1699389e05000000'
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] PHPWrapper->ImportMessageChange(): Getting message from MAPIProvider, sourcekey: '530f88894db34e14a8fde0045d4a1699269e05000000', parentsourcekey: '530f88894db34e14a8fde0045d4a16990301000000000000', entryid: '00000000706aeeeb7c034000b134ed4b8824266f010000000500000015e006be75ad924881bff287ab6e174800000000'
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] BodyPreference types: 1
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] GetBodyPreferenceBestMatch: 1
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] bpo: truncation size:'32768', allornone:'0', preview:'0'
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] MAPIStreamWrapper::stream_open(): initialized mapistream: Resource id #202 - streamlength: 41 - HTML-safe-truncate: false
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] TimezoneUtil::GetFullTZ() for Europe/Amsterdam
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] MAPIProvider->getAppointment(): setting ourself as the organizer for an appointment without attendees.
30/09/2017 16:45:15 [ 2142] [DEBUG] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] ZPush::GetAdditionalSyncFolderStore('530f88894db34e14a8fde0045d4a16990301000000000000'): 'false'
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O      <Add>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O       <ServerEntryId>
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O       Ua1d3e:530f88894db34e14a8fde0045d4a1699269e05000000
30/09/2017 16:45:15 [ 2142] [WBXML] [rconstap] [2hm2u8i68p5t76cbeht0qpikkg] O       </ServerEntryId>

Hi zwockel,

could you also post the output of z-push-admin -a list -u rconstap -d 2hm2u8i68p5t76cbeht0qpikkg?

Is this the entire log? In the part you’ve posted there’s nothing obviously wrong. The device is still synchronising the calendar folder and the “6632 changes ready to sync” indicates that it will still take some time.

Manfred

Hallo Manfred,

here is the output:
root@kopano:/var/log/z-push# z-push-admin -a list -u rconstap -d 2hm2u8i68p5t76cbeht0qpikkg
PHP Notice: Use of undefined constant rconstap - assumed ‘rconstap’ in /etc/z-push/z-push.conf.php on line 110

Synchronized by user: rconstap

DeviceId: 2hm2u8i68p5t76cbeht0qpikkg
Device type: iPhone
UserAgent: Apple-iPhone9C3/1501.421
Device Model: iPhone9C3
Device friendly name: Schwarz iPhone 7
Device OS: iOS 11.0.2 15A421
Device OS Language: de-DE
ActiveSync version: 14.0
First sync: 2017-07-21 21:36
Last sync: 2017-10-04 19:42
Total folders: 512
Short folder Ids: Yes
Synchronized folders: 459
Synchronized data: Emails(454) Contacts Calendars Notes Tasks(2)
Additional Folders: none
Status: OK
WipeRequest on: not set
WipeRequest by: not set
Wiped on: not set
Policy name: default
Attention needed: 1 messages need attention because they could not be synchronized
Broken object: ‘SyncAppointment’ ignored on '2017-09-30 19:00’
Information: Subject: ‘Geburtstag Luzia Becker’ - On: '1999-12-03 02:00’
Reason: (4)
Item/Parent id: Ua1d3e:530f88894db34e14a8fde0045d4a1699bb06000000000000/Ua1d3e

Best regards
Ralf

Hi Ralf,

is it still synchronising? iOS 11.0.2 had some fixes for ActiveSync accounts as far as I know.

Do you have something in $specialLogUsers in Z-Push config? The user names you put there must be quoted.

Manfred

Hallo Manfred,

after the iOS Update to 11.0.3 and reconect the calendar acount everything works.

Hi Ralf,

thanks for the update. Good to hear that it works now.

Manfred

Log in to reply

Looks like your connection to Kopano Community Forum was lost, please wait while we try to reconnect.