unable to delete calendar entry with mobile



  • Hi,

    I’m unable to delete an calendar entry through my mobile getting folllowing error/warning in z-push.log:

    03/09/2017 20:08:06 [ 8521] [ INFO] [ck] cmd='Sync' memory='3.09 MiB/3.50 MiB' time='0.52s' devType='iPhone' devId='hghm0us7q93on8k6rt9g2teao8' getUser='ck' from='192.168.2.66' idle='0s' version='2.3.8beta2+0-0' method='POST' httpcode='200'
    03/09/2017 20:08:08 [10517] [ INFO] [ck] SyncCollections->CheckForChanges(): Waiting for store changes... (lifetime 900 seconds)
    03/09/2017 20:08:19 [ 9179] [ INFO] [ck] cmd='FolderSync' memory='3.09 MiB/3.50 MiB' time='0.74s' devType='iPhone' devId='hghm0us7q93on8k6rt9g2teao8' getUser='ck' from='192.168.2.66' idle='0s' version='2.3.8beta2+0-0' method='POST' httpcode='200'
    03/09/2017 20:08:21 [ 8060] [ INFO] [ck] SyncCollections->CheckForChanges(): Waiting for store changes... (lifetime 900 seconds)
    03/09/2017 20:08:39 [10517] [ INFO] [ck] StatusException: SyncCollections->CheckForChanges(): Timeout forced after 31s from 900s due to other process - code: 3 - file: /usr/share/z-push/lib/core/synccollections.php:579
    03/09/2017 20:08:39 [10517] [ INFO] [ck] cmd='Ping' memory='3.35 MiB/3.75 MiB' time='30.85s' devType='iPhone' devId='hghm0us7q93on8k6rt9g2teao8' getUser='ck' from='192.168.2.66' idle='31s' version='2.3.8beta2+0-0' method='POST' httpcode='200'
    03/09/2017 20:08:48 [ 9180] [WARN] [ck] /usr/share/z-push/backend/kopano/importer.php:262 hex2bin(): Hexadecimal input string must have an even length (2)
    03/09/2017 20:08:48 [ 9180] [WARN] [ck] MAPIUtils->IsInCalendarSyncInterval: Message is OUTSIDE the synchronization interval
    03/09/2017 20:08:48 [ 9180] [WARN] [ck] ImportChangesICS->isModificationAllowed('Ua628a:4a3c0298843d4b29a322badb688e59b1462408000000'): Message in Calendar is outside the sync interval. Data not saved.
    03/09/2017 20:08:48 [ 9180] [ INFO] [ck] StatusException: ImportChangesICS->ImportMessageDeletion('Ua628a:4a3c0298843d4b29a322badb688e59b1462408000000'): Message deletion is not allowed. Deletion not executed. - code: 8 - file: /usr/share/z-push/backend/kopano/importer.php:461
    03/09/2017 20:08:48 [ 9180] [ INFO] [ck] Processed '1' incoming changes
    03/09/2017 20:08:48 [ 9180] [ INFO] [ck] KopanoBackend->Logoff(): Updating freebusy information on folder id '4a3c0298843d4b29a322badb688e59b1f02105000000'
    03/09/2017 20:08:48 [ 9180] [ INFO] [ck] cmd='Sync' memory='2.58 MiB/3.00 MiB' time='0.44s' devType='iPhone' devId='hghm0us7q93on8k6rt9g2teao8' getUser='ck' from='192.168.2.66' idle='0s' version='2.3.8beta2+0-0' method='POST' httpcode='200'
    03/09/2017 20:08:50 [ 7149] [ INFO] [ck] SyncCollections->CheckForChanges(): Waiting for store changes... (lifetime 900 seconds)
    03/09/2017 20:08:51 [ 8060] [ INFO] [ck] StatusException: SyncCollections->CheckForChanges(): Timeout forced after 30s from 900s due to other process - code: 3 - file: /usr/share/z-push/lib/core/synccollections.php:579
    03/09/2017 20:08:51 [ 8060] [ INFO] [ck] cmd='Ping' memory='3.35 MiB/3.75 MiB' time='30.75s' devType='iPhone' devId='hghm0us7q93on8k6rt9g2teao8' getUser='ck' from='192.168.2.66' idle='30s' version='2.3.8beta2+0-0' method='POST' httpcode='200'
    03/09/2017 20:09:02 [ 8717] [ INFO] [ck] SyncCollections->CheckForChanges(): Waiting for store changes... (lifetime 900 seconds)
    

    UCS 4.2-1 errata 157
    z-push 3.8.0beta2+0-0
    Kopano Core 8.3.4-12

    rg
    Christian


  • Kopano

    @externa1 said in unable to delete calendar entry with mobile:

    Message in Calendar is outside the sync interval.

    Is it?



  • @fbartels
    No it is not as it was created for Test on same day


  • Kopano

    @externa1 I tested this and created a ticket. Thanks for your help!


  • Kopano

    @externa1 Here is the link for the ticket:
    https://jira.z-hub.io/browse/ZP-1286


  • Kopano

    Hi Christian,

    could you please provide WBXML log?

    Manfred



  • @manfred

    Here is the part where the entry deletion was triggert:

    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] -------- Start
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] cmd='Sync' devType='iPhone' devId='hghm0us7q93on8k6rt9g2teao8' getUser='ck' from='192.168.2.66' version='2.3.8beta2+0-0' method='POST'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] Used timezone 'Europe/Amsterdam'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] ZPush::GetBackend(): trying autoload backend 'BackendKopano'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] BackendKopano using PHP-MAPI version: 8.3.4-12 - PHP version: 5.6.30-0+deb8u1
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] Request::ProcessHeaders() ASVersion: 14.0
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] ZPush::CommandNeedsProvisioning(0): true
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] FileStateMachine->GetState() read '10829' bytes from file: '/var/lib/z-push/8/o/hghm0us7q93on8k6rt9g2teao8-devicedata'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] ASDevice data loaded for user: 'ck'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] TopCollector(): Initialized mutexid Resource id #18 and memid Resource id #19.
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] LoopDetection(): Initialized mutexid Resource id #22 and memid Resource id #23.
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] LoopDetection initialised with IPC provider 'IpcSharedMemoryProvider' with type '1337'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] ZPush::HierarchyCommand(0): false
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] DeviceManager->ProvisioningRequired('818433754') saved device key '818433754': false
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] DeviceManager->getPolicyName(): determined policy name: 'default'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] DeviceManager->getProvisioningPolicies(): loaded 'default' policy.
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] ZPush::CommandNeedsAuthentication(0): true
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] KopanoBackend->Logon(): Trying to authenticate user 'ck'..
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] KopanoBackend->openMessageStore('ck'): Found 'DEFAULT' store: 'Resource id #34'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] KopanoBackend->Logon(): User 'ck' is authenticated
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] Store supports properties containing Unicode characters.
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] ZPush::CommandNeedsPlainInput(0): false
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I  <Synchronize>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I   <Folders>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I    <Folder>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I     <SyncKey>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I      {d36705d1-1a4e-47b5-8ef7-b3626f17fa52}417
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I     </SyncKey>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I     <FolderId>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I      Ua628a
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I     </FolderId>
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] FileStateMachine->GetState() read '1037' bytes from file: '/var/lib/z-push/8/o/hghm0us7q93on8k6rt9g2teao8-d36705d1-1a4e-47b5-8ef7-b3626f17fa52-fd'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] SyncParameters->UseCPO('DEFAULT')
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] DeviceManager->GetBackendIdForFolderId(): folderid Ua628a => 4a3c0298843d4b29a322badb688e59b1f02105000000
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] SyncCollections->AddCollection(): Folder id 'Ua628a' : ref. PolicyKey '818433754', ref. Lifetime '905', last sync at '1504534726'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] SyncCollections->AddCollection(): Updated reference PolicyKey '818433754', reference Lifetime '905', Last sync at '1504534726'
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I     <GetChanges/>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I     <WindowSize>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I      25
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I     </WindowSize>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I     <Options>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I      <FilterType>
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] SyncParameters->UseCPO('DEFAULT')
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I       5
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I      </FilterType>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I      <AirSyncBase:BodyPreference>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I       <AirSyncBase:Type>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I        1
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I       </AirSyncBase:Type>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I       <AirSyncBase:TruncationSize>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I        32768
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I       </AirSyncBase:TruncationSize>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I      </AirSyncBase:BodyPreference>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I     </Options>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I     <Perform>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I      <Remove>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I       <ServerEntryId>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I        Ua628a:4a3c0298843d4b29a322badb688e59b11e2b08000000
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I       </ServerEntryId>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I      </Remove>
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] Sync->getImporter(): initialize importer
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] Sync->loadStates(): loading states for folder 'Ua628a'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] FileStateMachine->CleanStates(): Deleting file: '/var/lib/z-push/8/o/hghm0us7q93on8k6rt9g2teao8-d36705d1-1a4e-47b5-8ef7-b3626f17fa52-416'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] FileStateMachine->GetState() read '15' bytes from file: '/var/lib/z-push/8/o/hghm0us7q93on8k6rt9g2teao8-d36705d1-1a4e-47b5-8ef7-b3626f17fa52-417'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] FileStateMachine->CleanStates(): Deleting file: '/var/lib/z-push/8/o/hghm0us7q93on8k6rt9g2teao8-d36705d1-1a4e-47b5-8ef7-b3626f17fa52-fs-415'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] ZPush::GetAdditionalSyncFolderStore('4a3c0298843d4b29a322badb688e59b1f02105000000'): 'false'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] BackendKopano->GetImporter() folderid: '4a3c0298843d4b29a322badb688e59b1f02105000000'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] ImportChangesICS->Config(): initializing importer with state: 0x8abe0000cb462800
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] ImportChangesICS->LoadConflicts(): will be loaded later if necessary
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] LoopDetection->SetSyncStateUsage(): uuid: d36705d1-1a4e-47b5-8ef7-b3626f17fa52  counter: 417
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] ZPush::GetAdditionalSyncFolderStore('4a3c0298843d4b29a322badb688e59b1f02105000000'): 'false'
    04/09/2017 16:18:56 [106203] [WARN] [ck] [hghm0us7q93on8k6rt9g2teao8] /usr/share/z-push/backend/kopano/importer.php:262 hex2bin(): Hexadecimal input string must have an even length (2)
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] ImportChangesICS->isModificationAllowed('Ua628a:4a3c0298843d4b29a322badb688e59b11e2b08000000'): cut off date is: 1501856336
    04/09/2017 16:18:56 [106203] [WARN] [ck] [hghm0us7q93on8k6rt9g2teao8] MAPIUtils->IsInCalendarSyncInterval: Message is OUTSIDE the synchronization interval
    04/09/2017 16:18:56 [106203] [WARN] [ck] [hghm0us7q93on8k6rt9g2teao8] ImportChangesICS->isModificationAllowed('Ua628a:4a3c0298843d4b29a322badb688e59b11e2b08000000'): Message in Calendar is outside the sync interval. Data not saved.
    04/09/2017 16:18:56 [106203] [ INFO] [ck] [hghm0us7q93on8k6rt9g2teao8] StatusException: ImportChangesICS->ImportMessageDeletion('Ua628a:4a3c0298843d4b29a322badb688e59b11e2b08000000'): Message deletion is not allowed. Deletion not executed. - code: 8 - file: /usr/share/z-push/backend/kopano/importer.php:461
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] Sync->importMessage(): message imported
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I     </Perform>
    04/09/2017 16:18:56 [106203] [ INFO] [ck] [hghm0us7q93on8k6rt9g2teao8] Processed '1' incoming changes
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] FileStateMachine->SetState() written 299 bytes on file: '/var/lib/z-push/8/o/hghm0us7q93on8k6rt9g2teao8-d36705d1-1a4e-47b5-8ef7-b3626f17fa52-fs-417'
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I    </Folder>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I   </Folders>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] I  </Synchronize>
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] HandleSync(): Start Output
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] ZPush::GetAdditionalSyncFolderStore('4a3c0298843d4b29a322badb688e59b1f02105000000'): 'false'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] KopanoBackend->GetFolderStat() fetched status information of 110 folders for store 'ck'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] SyncParameters->IsExporterRunRequired(): true - current: 1498233003/3230/0/0 - saved: 1498233003/3230/0/0 - expiring: 2017-10-05 10:07:29
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] ZPush::GetAdditionalSyncFolderStore('4a3c0298843d4b29a322badb688e59b1f02105000000'): 'false'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] ExportChangesICS->Config() initialized with state: 0x8abe0000cb462800
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] ExportChangesICS->InitializeExporter() successfully. 0 changes ready to sync for '4a3c0298843d4b29a322badb688e59b1f02105000000'.
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] LoopDetection->ProcessLoopDetectionAddStatus: 'Ua628a' with status 1
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] Folder type: Calendar
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O  <Synchronize>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O   <Folders>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O    <Folder>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O     <SyncKey>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O     {d36705d1-1a4e-47b5-8ef7-b3626f17fa52}418
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O     </SyncKey>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O     <FolderId>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O     Ua628a
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O     </FolderId>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O     <Status>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O     1
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O     </Status>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O     <Replies>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O      <Remove>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O       <ServerEntryId>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O       Ua628a:4a3c0298843d4b29a322badb688e59b11e2b08000000
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O       </ServerEntryId>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O       <Status>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O       8
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O       </Status>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O      </Remove>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O     </Replies>
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] LoopDetection->Detect(): folderid:'Ua628a' uuid:'d36705d1-1a4e-47b5-8ef7-b3626f17fa52' counter:'417' max:'25' queued:'0'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] ZPush::GetAdditionalSyncFolderStore('4a3c0298843d4b29a322badb688e59b1f02105000000'): 'false'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] Sync()->setFolderStat() on Ua628a: 1498233003/3230/0/0 expiring 2017-10-05 12:39:01
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O    </Folder>
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] FileStateMachine->SetState() written 15 bytes on file: '/var/lib/z-push/8/o/hghm0us7q93on8k6rt9g2teao8-d36705d1-1a4e-47b5-8ef7-b3626f17fa52-418'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] SyncCollections->SaveCollection(): Data of folder 'Ua628a' changed
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] FileStateMachine->SetState() written 1037 bytes on file: '/var/lib/z-push/8/o/hghm0us7q93on8k6rt9g2teao8-d36705d1-1a4e-47b5-8ef7-b3626f17fa52-fd'
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O   </Folders>
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] O  </Synchronize>
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] WBXMLEncoder->endTag() WBXML output completed
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] WBXML-OUT: AwFqAEVcT0sDe2QzNjcwNWQxLTFhNGUtNDdiNS04ZWY3LWIzNjI2ZjE3ZmE1Mn00MTgAAVIDVWE2MjhhAAFOAzEAAUZJTQNVYTYyOGE6NGEzYzAyOTg4NDNkNGIyOWEzMjJiYWRiNjg4ZTU5YjExZTJiMDgwMDAwMDAAAU4DOAABAQEBAQE=
    04/09/2017 16:18:56 [106203] [WBXML] [ck] [hghm0us7q93on8k6rt9g2teao8] WBXML-IN : AwFqAEVcT0sDe2QzNjcwNWQxLTFhNGUtNDdiNS04ZWY3LWIzNjI2ZjE3ZmE1Mn00MTcAAVIDVWE2MjhhAAETVQMyNQABV1gDNQABABFFRgMxAAFHAzMyNzY4AAEBAQAAVklNA1VhNjI4YTo0YTNjMDI5ODg0M2Q0YjI5YTMyMmJhZGI2ODhlNTliMTFlMmIwODAwMDAwMAABAQEBAQE=
    04/09/2017 16:18:56 [106203] [ INFO] [ck] [hghm0us7q93on8k6rt9g2teao8] KopanoBackend->Logoff(): Updating freebusy information on folder id '4a3c0298843d4b29a322badb688e59b1f02105000000'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] LoopDetection->ProcessLoopDetectionTerminate()
    04/09/2017 16:18:56 [106203] [ INFO] [ck] [hghm0us7q93on8k6rt9g2teao8] cmd='Sync' memory='2.84 MiB/3.25 MiB' time='0.68s' devType='iPhone' devId='hghm0us7q93on8k6rt9g2teao8' getUser='ck' from='192.168.2.66' idle='0s' version='2.3.8beta2+0-0' method='POST' httpcode='200'
    04/09/2017 16:18:56 [106203] [DEBUG] [ck] [hghm0us7q93on8k6rt9g2teao8] -------- End```
    
    rg
    Christian

  • Kopano

    Hi Christian,

    we found the problem. The fix will be available in 2.3.8 final and even sooner in the pre-final repositories.

    Manfred



  • @manfred

    great :)

    thanks


Log in to reply
 

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