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-12rg
Christian -
@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 -
@externa1 I tested this and created a ticket. Thanks for your help!
-
@externa1 Here is the link for the ticket:
https://jira.z-hub.io/browse/ZP-1286 -
Hi Christian,
could you please provide WBXML log?
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
-
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
-