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’


  • Kopano

    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?


  • Kopano

    @zwockel said in Calendar synchronisation with iOS 11:

    How can i create a wbxml?

    You can find the needed instructions in the wiki: https://wiki.z-hub.io/plugins/servlet/mobile#content/view/5734653



  • 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>
    

  • Kopano

    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


  • Kopano

    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.


  • Kopano

    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.