ZPush Issue after zarafa to Kopana migration
-
fbartels
I uninstall z-push, reboot the server, remove z-push folders, and reinstall z-push
in the etc\z-push folder, I find the config.php. and the z-push.conf.php
Which one is the right one, or are both files required?
While when I review the log settings off the config.php,/********************************************************************************** * Logging settings * Possible LOGLEVEL and LOGUSERLEVEL values are: * LOGLEVEL_OFF - no logging * LOGLEVEL_FATAL - log only critical errors * LOGLEVEL_ERROR - logs events which might require corrective actions * LOGLEVEL_WARN - might lead to an error or require corrective actions in the future * LOGLEVEL_INFO - usually completed actions * LOGLEVEL_DEBUG - debugging information, typically only meaningful to developers * LOGLEVEL_WBXML - also prints the WBXML sent to/from the device * LOGLEVEL_DEVICEID - also prints the device id for every log entry * LOGLEVEL_WBXMLSTACK - also prints the contents of WBXML stack * * The verbosity increases from top to bottom. More verbose levels include less verbose * ones, e.g. setting to LOGLEVEL_DEBUG will also output LOGLEVEL_FATAL, LOGLEVEL_ERROR, * LOGLEVEL_WARN and LOGLEVEL_INFO level entries. */ define('LOGFILEDIR', '/var/log/z-push/'); define('LOGFILE', LOGFILEDIR . 'z-push.log'); define('LOGERRORFILE', LOGFILEDIR . 'z-push-error.log'); define('LOGLEVEL', LOGLEVEL_INFO); define('LOGAUTHFAIL', false); //define('LOGLEVEL', LOGLEVEL_WBXML); // To save e.g. WBXML data only for selected users, add the usernames to the array // The data will be saved into a dedicated file per user in the LOGFILEDIR // Users have to be encapusulated in quotes, several users are comma separated, like: // $specialLogUsers = array('info@domain.com', 'myusername'); define('LOGUSERLEVEL', LOGLEVEL_DEVICEID); $specialLogUsers = array('user1', 'User2');I still didn’t find the user1, abd user2 log file in the log folder.
-
@goudduif when running
ls -la /usr/share/z-push/config.phpyou see which config file is used by z-push.For me it is:
# ls -la /usr/share/z-push/config.php lrwxrwxrwx 1 root root 27 Mär 3 15:11 /usr/share/z-push/config.php -> /etc/z-push/z-push.conf.php -
While there where still some issues.
I remove z-push totally with " univention-remove z-push*"
But now when I reinstall Z-push from the appcenter, there’s nothing in the \etc\z-push folder
How can I solve this issue?
-
@goudduif your screenshot does not show the univention appcenter. you should remove/reinstall z-push through the appcenter and not the package management.
-
That’s exactly what I did, I reinstall them from appcenter.
But there I didn’t find anything on etc/z-push, I review what’s happens on Package management. -
I retore the config file from Backup, so Z-Push works, but there still some issue’s
There are different users, with two different devices.
One off thensync all mails without a issue, the other one, didn’t receive any data, until she reboot their one-plus-one phone.
After the reboot, it sync the mail, and calendar. (no Contac’s) only one time.
even when I enable the WBXML logging, it didn’t show anything regarding this device.
The strange issue is, that everything works fine till we upgrade from Zarafa to Kopano.
Below you find the WBXML logging from the device that sync only after a reboot.
the WBXML file didn’t show any data off this device till the user reboot their device.If you like it I can send you the WBXML file.
-
If there is nothing in the z-push log and also nothing in the webserver access & error logs then the device most probably isn’t connecting to the server.
Did you also change the ssl configuration? Could it be that the mobile isn’t recognizing your (new) certificate? -
28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] -------- Start 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] cmd='Sync' devType='Android' devId='boxerc543471768' getUser='user1' from='10.0.0.96' version='2.3.5+4' method='POST' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] Used timezone 'Europe/Amsterdam' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ZPush::GetBackend(): trying autoload backend 'BackendKopano' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] BackendKopano using PHP-MAPI version: 8.1.1-10 - PHP version: 5.4.45-0.235.201611171529 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] Request::ProcessHeaders() ASVersion: 14.0 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ZPush::CommandNeedsProvisioning(0): true 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] FileStateMachine->GetState() read '3431' bytes from file: '/var/lib/z-push/8/6/boxerc543471768-devicedata' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ASDevice data loaded for user: 'user1' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] TopCollector(): Initialized mutexid Resource id #57 and memid Resource id #58. 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] LoopDetection(): Initialized mutexid Resource id #62 and memid Resource id #63. 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] LoopDetection initialised with IPC provider 'IpcSharedMemoryProvider' with type '1337' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ZPush::HierarchyCommand(0): false 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] DeviceManager->ProvisioningRequired('603050742') saved device key '603050742': false 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] DeviceManager->getPolicyName(): determined policy name: 'default' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] DeviceManager->getProvisioningPolicies(): loaded 'default' policy. 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ZPush::CommandNeedsAuthentication(0): true 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] KopanoBackend->Logon(): Trying to authenticate user 'user1'.. 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] KopanoBackend->openMessageStore('user1'): Found 'DEFAULT' store: 'Resource id #78' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] KopanoBackend->Logon(): User 'user1' is authenticated 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] Store supports properties containing Unicode characters. 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ZPush::CommandNeedsPlainInput(0): false 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I <Synchronize> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I <Folders> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I <Folder> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I <SyncKey> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I {1a66cdee-6888-49e0-a6f3-cf1dbdc512f5}8 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I </SyncKey> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I <FolderId> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I Udd5bb 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I </FolderId> 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] FileStateMachine->GetState() read '945' bytes from file: '/var/lib/z-push/8/6/boxerc543471768-1a66cdee-6888-49e0-a6f3-cf1dbdc512f5-fd' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] SyncParameters->UseCPO('DEFAULT') 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] DeviceManager->GetBackendIdForFolderId(): folderid Udd5bb => c8b30938bf774f6897130e721f8d3ed6590000000000 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] SyncCollections->AddCollection(): Folder id 'Udd5bb' : ref. PolicyKey '603050742', ref. Lifetime '', last sync at '1490724488' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] SyncCollections->AddCollection(): Updated reference PolicyKey '603050742', reference Lifetime '', Last sync at '1490724488' 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I <DeletesAsMoves/> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I <GetChanges/> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I <WindowSize> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I 10 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I </WindowSize> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I <Options> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I <FilterType> 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] SyncParameters->UseCPO('DEFAULT') 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I 4 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I </FilterType> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I <AirSyncBase:BodyPreference> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I <AirSyncBase:Type> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I 1 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I </AirSyncBase:Type> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I <AirSyncBase:TruncationSize> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I 200000 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I </AirSyncBase:TruncationSize> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I </AirSyncBase:BodyPreference> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I </Options> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I </Folder> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I </Folders> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I </Synchronize> 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] HandleSync(): Start Output 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ZPush::GetAdditionalSyncFolderStore('c8b30938bf774f6897130e721f8d3ed6590000000000'): 'false' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] KopanoBackend->GetFolderStat() fetched status information of 40 folders for store 'user1' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] SyncParameters->IsExporterRunRequired(): true - current: 1459353174/114/0/4 - saved: false - expiring: not set 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] Sync->loadStates(): loading states for folder 'Udd5bb' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] FileStateMachine->CleanStates(): Deleting file: '/var/lib/z-push/8/6/boxerc543471768-1a66cdee-6888-49e0-a6f3-cf1dbdc512f5-7' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] FileStateMachine->GetState() read '231' bytes from file: '/var/lib/z-push/8/6/boxerc543471768-1a66cdee-6888-49e0-a6f3-cf1dbdc512f5-8' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ZPush::GetAdditionalSyncFolderStore('c8b30938bf774f6897130e721f8d3ed6590000000000'): 'false' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ZPush::GetAdditionalSyncFolderStore('c8b30938bf774f6897130e721f8d3ed6590000000000'): 'false' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ExportChangesICS->Config(): synching inital data 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ExportChangesICS->Config() initialized with state: 0x943500000000000007000000fc4c030016000000c8b30938bf774f6897130e721f8d3ed6023801000000d350030016000000c8b30938bf774f6897130e721f8d3ed6cd3901000000d450030016000000c8b30938bf774f6897130e721f8d3ed6ce39010000001f66030016000000c8b30938bf774f6897130e721f8d3ed69c3d010000002066030016000000c8b30938bf774f6897130e721f8d3ed69d3d010000007f6a030016000000c8b30938bf774f6897130e721f8d3ed6514001000000806a030016000000c8b30938bf774f6897130e721f8d3ed6524001000000 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ExportChangesICS->InitializeExporter() successfully. 2 changes ready to sync for 'c8b30938bf774f6897130e721f8d3ed6590000000000'. 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] LoopDetection->ProcessLoopDetectionAddStatus: 'Udd5bb' with status 1 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] Folder type: Calendar 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O <Synchronize> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O <Folders> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O <Folder> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O <SyncKey> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O {1a66cdee-6888-49e0-a6f3-cf1dbdc512f5}9 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O </SyncKey> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O <FolderId> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O Udd5bb 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O </FolderId> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O <Status> 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O 1 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O </Status> 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] LoopDetection->Detect(): folderid:'Udd5bb' uuid:'1a66cdee-6888-49e0-a6f3-cf1dbdc512f5' counter:'8' max:'10' queued:'2' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] LoopDetection->Detect(): case 1.2 detected 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] LoopDetection->Detect(): case 1.2.1 detected 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] LoopDetection->Detect(): loop data: loopcount(1), maxCount(10), queued(2), ignored(false) 28/03/2017 20:08:08 [21368] [WARN] [user1] [boxerc543471768] Mobile loop detected! Messages sent to the mobile will be restricted to 1 items in order to identify the conflict 28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O <MoreAvailable/> 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] PHPWrapper->ImportMessageChange(): Getting message from MAPIProvider, sourcekey: 'c8b30938bf774f6897130e721f8d3ed6363501000000', parentsourcekey: 'c8b30938bf774f6897130e721f8d3ed6590000000000', entryid: '000000005a5734edde3d428fb3a1251b1f41c7120100000005000000b6cd09a7a3d9402e92ae6bebd203c5a700000000' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ZPush::GetAdditionalSyncFolderStore('c8b30938bf774f6897130e721f8d3ed6590000000000'): 'false' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] BodyPreference types: 1 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] GetBodyPreferenceBestMatch: 1 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] bpo: truncation size:'200000', allornone:'0', preview:'0' 28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] MAPIStreamWrapper::stream_open(): initialized mapistream: streamlength: 0 28/03/2017 20:08:09 [21368] [DEBUG] [user1] [boxerc543471768] TimezoneUtil::FillTZNames() filling up bias -60 28/03/2017 20:08:09 [21368] [DEBUG] [user1] [boxerc543471768] MAPIProvider->GetStoreProps(): Getting store properties. 28/03/2017 20:08:09 [21368] [DEBUG] [user1] [boxerc543471768] LoopDetection->IgnoreNextMessage(): Loop mode, potential broken message id 'Udd5bb:c8b30938bf774f6897130e721f8d3ed6363501000000' 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <Perform> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <Add> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <ServerEntryId> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O Udd5bb:c8b30938bf774f6897130e721f8d3ed6363501000000 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </ServerEntryId> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <Data> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Timezone> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O xP///ygARwBNAFQAKwAwADEAOgAwADAAKQAgAEIAZQBsAGcAcgBhAGQAZQAsACAAQgByAGEAdABpAHMAbABhAHYAYQAAAAEAAAABAAMAAAAAAAAAAAAAACgARwBNAFQAKwAwADEAOgAwADAAKQAgAEIAZQBsAGcAcgBhAGQAZQAsACAAQgByAGEAdABpAHMAbABhAHYAYQAAAAAAAAAAAAAAAAAAAAAAAAAAAA== 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Timezone> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:DtStamp> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O 20170228T200033Z 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:DtStamp> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:StartTime> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O 20170509T173000Z 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:StartTime> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Subject> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O shopping 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Subject> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:UID> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O 76E3FD9EED9446BAB899C975763E450A0 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:UID> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:OrganizerName> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O user2 morris 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:OrganizerName> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:OrganizerEmail> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O user2@domain.org 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:OrganizerEmail> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:EndTime> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O 20170509T183000Z 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:EndTime> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Sensitivity> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O 0 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Sensitivity> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:BusyStatus> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O 1 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:BusyStatus> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:AllDayEvent> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O 0 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:AllDayEvent> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:MeetingStatus> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O 3 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:MeetingStatus> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Attendees> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Attendee> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Email> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O user7dune@business.org 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Email> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Name> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O user7dune@business.org 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Name> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Attendee_Type> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O 1 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Attendee_Type> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Attendee> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Attendee> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Email> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O user5@domain.org 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Email> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Name> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O user5 morris 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Name> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Attendee_Type> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O 1 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Attendee_Type> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Attendee> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Attendee> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Email> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O user1@domain.org 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Email> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Name> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O user1 morris 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Name> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Attendee_Type> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O 1 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Attendee_Type> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Attendee> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Attendee> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Email> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O user4@domain.org 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Email> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Name> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O user4 morris 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Name> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:Attendee_Type> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O 1 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Attendee_Type> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Attendee> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:Attendees> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <AirSyncBase:Body> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <AirSyncBase:Type> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O 1 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </AirSyncBase:Type> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <AirSyncBase:EstimatedDataSize> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O 0 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </AirSyncBase:EstimatedDataSize> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <AirSyncBase:Data> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <<< written 0 of 0 bytes of plain data >>> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </AirSyncBase:Data> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </AirSyncBase:Body> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <AirSyncBase:NativeBodyType> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O 1 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </AirSyncBase:NativeBodyType> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O <POOMCAL:ResponseType> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O 5 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </POOMCAL:ResponseType> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </Data> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </Add> 28/03/2017 20:08:09 [21368] [DEBUG] [user1] [boxerc543471768] PHPWrapper->ImportMessageChange(): change for: 'Udd5bb:c8b30938bf774f6897130e721f8d3ed6363501000000' 28/03/2017 20:08:09 [21368] [DEBUG] [user1] [boxerc543471768] HandleSync(): Exported maxItems of messages: 1 / 2 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </Perform> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </Folder> 28/03/2017 20:08:09 [21368] [DEBUG] [user1] [boxerc543471768] FileStateMachine->SetState() written 945 bytes on file: '/var/lib/z-push/8/6/boxerc543471768-1a66cdee-6888-49e0-a6f3-cf1dbdc512f5-fd' 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </Folders> 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O </Synchronize> 28/03/2017 20:08:09 [21368] [DEBUG] [user1] [boxerc543471768] WBXMLEncoder->endTag() WBXML output completed 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] WBXML-OUT: AwFqAEVcT0sDezFhNjZjZGVlLTY4ODgtNDllMC1hNmYzLWNmMWRiZGM1MTJmNX05AAFSA1VkZDViYgABTgMxAAEUVkdNA1VkZDViYjpjOGIzMDkzOGJmNzc0ZjY4OTcxMzBlNzIxZjhkM2VkNjM2MzUwMTAwMDAwMAABXQAERQN4UC8vL3lnQVJ3Qk5BRlFBS3dBd0FERUFPZ0F3QURBQUtRQWdBRUlBWlFCc0FHY0FjZ0JoQUdRQVpRQXNBQ0FBUWdCeUFHRUFkQUJwQUhNQWJBQmhBSFlBWVFBQUFBRUFBQUFCQUFNQUFBQUFBQUFBQUFBQUFDZ0FSd0JOQUZRQUt3QXdBREVBT2dBd0FEQUFLUUFnQUVJQVpRQnNBR2NBY2dCaEFHUUFaUUFzQUNBQVFnQnlBR0VBZEFCcEFITUFiQUJoQUhZQVlRQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQT09AAFRAzIwMTcwMjI4VDIwMDAzM1oAAWcDMjAxNzA1MDlUMTczMDAwWgABZgNLbmlwcGVuAAFoAzc2RTNGRDlFRUQ5NDQ2QkFCODk5Qzk3NTc2M0U0NTBBMAABWgNXaW0gR3VpanQAAVkDd2ltQHdncGMubmwAAVIDMjAxNzA1MDlUMTgzMDAwWgABZQMwAAFNAzEAAUYDMAABWAMzAAFHSEkDbGVvbmlldmFuZHVpam5AbGl2ZS5ubAABSgNsZW9uaWV2YW5kdWlqbkBsaXZlLm5sAAFqAzEAAQFISQNjb3JuZUB3Z3BjLm5sAAFKA0Nvcm5lIEd1aWp0AAFqAzEAAQFISQNsZXVudGplQHdncGMubmwAAUoDTGV1bnRqZSBHdWlqdAABagMxAAEBSEkDYXJpZUB3Z3BjLm5sAAFKA0FyaWUgR3VpanQAAWoDMQABAQEAEUpGAzEAAUwDMAABSwMAAQFWAzEAAQAEdgM1AAEBAQEBAQE= 28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] WBXML-IN : AwFqAEVcT0sDezFhNjZjZGVlLTY4ODgtNDllMC1hNmYzLWNmMWRiZGM1MTJmNX04AAFSA1VkZDViYgABHhNVAzEwAAFXWAM0AAEAEUVGAzEAAUcDMjAwMDAwAAEBAQEBAQ== 28/03/2017 20:08:09 [21368] [DEBUG] [user1] [boxerc543471768] LoopDetection->ProcessLoopDetectionTerminate() 28/03/2017 20:08:09 [21368] [ INFO] [user1] [boxerc543471768] cmd='Sync' memory='6.90 MiB/7.25 MiB' time='0.75s' devType='Android' devId='boxerc543471768' getUser='user1' from='10.0.0.96' version='2.3.5+4' method='POST' httpcode='200' 28/03/2017 20:08:09 [21368] [DEBUG] [user1] [boxerc543471768] -------- EndHow can I solve this issue?
-
The other device, of this user, show me also the following!
Folder: Calendar Sync: Synchronizing Status: 6% (7/114) Additional Folders: none Status: OK WipeRequest on: not set WipeRequest by: not set Wiped on: not set Policy name: default Attention needed: 4 messages need attention because they could not be synchronized Broken object: 'SyncMail' ignored on '2017-03-28 05:14' Information: Subject: 'Userday' - From: '"user2 Morris" <user2@domain.org>' Reason: Message was causing loop (2) Item/Parent id: U16cff:c8b30938bf774f6897130e721f8d3ed6da3a01000000/U16cff Broken object: 'SyncMail' ignored on '2017-03-28 19:27' Information: Subject: 'shopping' - From: '"user2 Morris" <user2@domain.org>' Reason: Message was causing loop (2) Item/Parent id: U16cff:c8b30938bf774f6897130e721f8d3ed6373501000000/U16cff Broken object: 'SyncAppointment' ignored on '2017-03-28 20:46' Information: Subject: 'shopping' - On: '2017-05-09 19:30' Reason: Message was causing loop (2) Item/Parent id: Udd5bb:c8b30938bf774f6897130e721f8d3ed6363501000000/Udd5bb Broken object: 'SyncAppointment' ignored on '2017-03-28 20:46' Information: Subject: 'shopping' - On: '2017-04-05 19:30' Reason: Message was causing loop (2) Item/Parent id: Udd5bb:c8b30938bf774f6897130e721f8d3ed62a3501000000/Udd5bbI’ve already resync the users devices, run a clearloop, , and a fixstatus.
I’m afraid that this is the reason why the contacts are not synced.Synchronization progress: -
The loop detection is somewhat normal, depending on the circumstances. It could be that some appointments or contacts are also broken. Detailed information about how it works is available here: https://wiki.z-hub.io/display/ZP/Loop+detection
You could run clearloop with z-push-admin but depending on the issues z-push finds it will just start loop detection again.
Can you run kopano-fsck on these stores?
Cheers,
Sebastian
