Contacts looping

[Maxxer]

New day, new problem.
Since yesterday one of my collegues has problem in his sync, phone drains battery and of course sync is not performing well.
Mail and calendar seem to work fine, but contacts are looping.

These are the last lines of z-push.log:
13/02/2015 09:03:40 [28295] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:03:40 [28295] [INFO] [riccardo.sama@domain.it] [android1420726834315] ExportChangesDiff->InitializeExporter(): Found '1152' changes
13/02/2015 09:03:40 [15222] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:03:40 [15222] [INFO] [riccardo.sama@domain.it] [android1420726834315] StateNotFoundException: FileStateMachine->GetState(): Could not locate state '/var/lib/z-push/5/1/android1420726834315-d95b4993-d2b9-4dd3-8fe8-dae4a792811d-2' - code: 0
13/02/2015 09:03:42 [15222] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:03:42 [28295] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:03:42 [15222] [INFO] [riccardo.sama@domain.it] [android1420726834315] ExportChangesDiff->InitializeExporter(): Found '1152' changes
13/02/2015 09:03:42 [28295] [INFO] [riccardo.sama@domain.it] [android1420726834315] StateNotFoundException: FileStateMachine->GetState(): Could not locate state '/var/lib/z-push/5/1/android1420726834315-d95b4993-d2b9-4dd3-8fe8-dae4a792811d-fd' - code: 0
13/02/2015 09:03:42 [28295] [INFO] [riccardo.sama@domain.it] [android1420726834315] StateNotFoundException: FileStateMachine->GetState(): Could not locate state '/var/lib/z-push/5/1/android1420726834315-748be4d8-da98-4f5f-9880-c5484f28b9e6-1' - code: 0
13/02/2015 09:03:43 [28295] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:03:43 [15222] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:03:43 [28295] [INFO] [riccardo.sama@domain.it] [android1420726834315] ExportChangesDiff->InitializeExporter(): Found '1152' changes
13/02/2015 09:03:44 [15222] [INFO] [riccardo.sama@domain.it] [android1420726834315] StateNotFoundException: FileStateMachine->GetState(): Could not locate state '/var/lib/z-push/5/1/android1420726834315-748be4d8-da98-4f5f-9880-c5484f28b9e6-fd' - code: 0
13/02/2015 09:03:44 [15222] [INFO] [riccardo.sama@domain.it] [android1420726834315] ExportChangesDiff->InitializeExporter(): Found '1152' changes
13/02/2015 09:03:44 [28295] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:03:44 [28295] [INFO] [riccardo.sama@domain.it] [android1420726834315] ExportChangesDiff->InitializeExporter(): Found '1152' changes
13/02/2015 09:03:44 [28295] [WARN] [riccardo.sama@domain.it] [android1420726834315] Mobile loop detected! Messages sent to the mobile will be restricted to 1 items in order to identify the conflict
13/02/2015 09:03:46 [28295] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:03:47 [28295] [INFO] [riccardo.sama@domain.it] [android1420726834315] ExportChangesDiff->InitializeExporter(): Found '1151' changes
13/02/2015 09:03:47 [28295] [WARN] [riccardo.sama@domain.it] [android1420726834315] Mobile loop detected! Messages sent to the mobile will be restricted to 1 items in order to identify the conflict
13/02/2015 09:03:48 [28295] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:03:48 [15222] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:03:48 [28295] [INFO] [riccardo.sama@domain.it] [android1420726834315] StateNotFoundException: FileStateMachine->GetState(): Could not locate state '/var/lib/z-push/5/1/android1420726834315-d316a1e9-f778-4607-8b35-133653425505-2' - code: 0
13/02/2015 09:03:48 [28295] [WARN] [riccardo.sama@domain.it] [android1420726834315] Mobile loop detected! Messages sent to the mobile will be restricted to 1 items in order to identify the conflict
13/02/2015 09:03:48 [15222] [INFO] [riccardo.sama@domain.it] [android1420726834315] StateNotFoundException: FileStateMachine->GetState(): Could not locate state '/var/lib/z-push/5/1/android1420726834315-178c2a49-58da-4460-b71c-ccb0844544ff-fd' - code: 0
13/02/2015 09:03:48 [15222] [INFO] [riccardo.sama@domain.it] [android1420726834315] StateNotFoundException: FileStateMachine->GetState(): Could not locate state '/var/lib/z-push/5/1/android1420726834315-178c2a49-58da-4460-b71c-ccb0844544ff-3' - code: 0
13/02/2015 09:03:50 [15222] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:03:50 [28295] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:03:50 [15222] [INFO] [riccardo.sama@domain.it] [android1420726834315] ExportChangesDiff->InitializeExporter(): Found '1152' changes
13/02/2015 09:03:50 [28295] [INFO] [riccardo.sama@domain.it] [android1420726834315] StateNotFoundException: FileStateMachine->GetState(): Could not locate state '/var/lib/z-push/5/1/android1420726834315-d316a1e9-f778-4607-8b35-133653425505-fd' - code: 0
13/02/2015 09:03:50 [28295] [INFO] [riccardo.sama@domain.it] [android1420726834315] ExportChangesDiff->InitializeExporter(): Found '1152' changes
13/02/2015 09:03:51 [28295] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:03:51 [15222] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:03:51 [28295] [INFO] [riccardo.sama@domain.it] [android1420726834315] ExportChangesDiff->InitializeExporter(): Found '1152' changes
13/02/2015 09:03:51 [15222] [INFO] [riccardo.sama@domain.it] [android1420726834315] StateNotFoundException: FileStateMachine->GetState(): Could not locate state '/var/lib/z-push/5/1/android1420726834315-e391db4b-e4a9-4dc9-a59b-a5e6720a5c1c-fd' - code: 0
13/02/2015 09:03:51 [15222] [INFO] [riccardo.sama@domain.it] [android1420726834315] StateNotFoundException: FileStateMachine->GetState(): Could not locate state '/var/lib/z-push/5/1/android1420726834315-e391db4b-e4a9-4dc9-a59b-a5e6720a5c1c-1' - code: 0
13/02/2015 09:03:51 [15222] [WARN] [riccardo.sama@domain.it] [android1420726834315] Mobile loop detected! Messages sent to the mobile will be restricted to 1 items in order to identify the conflict
13/02/2015 09:03:52 [15222] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:03:52 [15222] [INFO] [riccardo.sama@domain.it] [android1420726834315] ExportChangesDiff->InitializeExporter(): Found '1152' changes
13/02/2015 09:03:57 [15222] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:03:58 [15222] [INFO] [riccardo.sama@domain.it] [android1420726834315] ExportChangesDiff->InitializeExporter(): Found '1152' changes
13/02/2015 09:03:58 [ 505] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:03:58 [ 505] [INFO] [riccardo.sama@domain.it] [android1420726834315] StateNotFoundException: FileStateMachine->GetState(): Could not locate state '/var/lib/z-push/5/1/android1420726834315-df5c80e1-6d5b-48cd-a5ef-5f433f8bfdc6-2' - code: 0
13/02/2015 09:04:01 [ 505] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:04:01 [ 505] [INFO] [riccardo.sama@domain.it] [android1420726834315] ExportChangesDiff->InitializeExporter(): Found '1152' changes
13/02/2015 09:04:10 [ 8936] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:04:10 [ 8936] [INFO] [riccardo.sama@domain.it] [android1420726834315] ExportChangesDiff->InitializeExporter(): Found '1152' changes
13/02/2015 09:04:11 [31920] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:04:11 [31920] [INFO] [riccardo.sama@domain.it] [android1420726834315] StateNotFoundException: FileStateMachine->GetState(): Could not locate state '/var/lib/z-push/5/1/android1420726834315-e2c88bbf-3b85-4b12-9e41-de10c8ddbea2-2' - code: 0
13/02/2015 09:04:13 [31920] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:04:14 [31920] [INFO] [riccardo.sama@domain.it] [android1420726834315] ExportChangesDiff->InitializeExporter(): Found '1152' changes
13/02/2015 09:04:14 [ 8936] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:04:14 [31920] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:04:14 [31920] [INFO] [riccardo.sama@domain.it] [android1420726834315] StateNotFoundException: FileStateMachine->GetState(): Could not locate state '/var/lib/z-push/5/1/android1420726834315-53a7e9e0-aba5-4c91-95c3-87ef7202d4b2-2' - code: 0
13/02/2015 09:04:14 [ 8936] [INFO] [riccardo.sama@domain.it] [android1420726834315] ExportChangesDiff->InitializeExporter(): Found '1152' changes
13/02/2015 09:04:15 [31920] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:04:15 [31920] [INFO] [riccardo.sama@domain.it] [android1420726834315] ExportChangesDiff->InitializeExporter(): Found '1152' changes
13/02/2015 09:04:17 [31920] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:04:17 [31920] [INFO] [riccardo.sama@domain.it] [android1420726834315] ExportChangesDiff->InitializeExporter(): Found '1152' changes
13/02/2015 09:04:17 [31920] [WARN] [riccardo.sama@domain.it] [android1420726834315] Mobile loop detected! Messages sent to the mobile will be restricted to 1 items in order to identify the conflict
13/02/2015 09:04:23 [28295] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:04:23 [15208] [INFO] [riccardo.sama@domain.it] [android1420726834315] Version='2.1.3-1892' method='POST' from='91.253.219.139' cmd='Sync' getUser='riccardo.sama@domain.it' devId='android1420726834315' devType='Android'
13/02/2015 09:04:23 [15208] [INFO] [riccardo.sama@domain.it] [android1420726834315] StateNotFoundException: FileStateMachine->GetState(): Could not locate state '/var/lib/z-push/5/1/android1420726834315-1599d99d-6001-47f6-9a84-6fcc838cc31d-2' - code: 0
13/02/2015 09:04:23 [15208] [WARN] [riccardo.sama@domain.it] [android1420726834315] Mobile loop detected! Messages sent to the mobile will be restricted to 1 items in order to identify the conflict

And z-push-error.log:
13/02/2015 09:00:39 [28295] [ERROR] [riccardo.sama@domain.it] [android1420726834315] Ignored broken message (SyncContact). Reason: '2' Folderid: 'f7' message id '115706'
13/02/2015 09:00:51 [31979] [ERROR] [riccardo.sama@domain.it] [android1420726834315] Ignored broken message (SyncContact). Reason: '2' Folderid: 'f7' message id '115689'
13/02/2015 09:01:00 [31920] [ERROR] [riccardo.sama@domain.it] [android1420726834315] Ignored broken message (SyncContact). Reason: '2' Folderid: 'f7' message id '115676'
13/02/2015 09:01:08 [32405] [ERROR] [riccardo.sama@domain.it] [android1420726834315] Ignored broken message (SyncContact). Reason: '2' Folderid: 'f7' message id '115672'
13/02/2015 09:01:32 [ 505] [ERROR] [riccardo.sama@domain.it] [android1420726834315] Ignored broken message (SyncContact). Reason: '2' Folderid: 'f7' message id '115665'

If needed I can post portions of WBXML log...
thanks

P.S. I already tried removing device data twice, and even removing the account from the phone

[LiverpoolFCfan]

I have seen this with recent versions of android, and have reported it to z-push too. The device can get into s state where it starts multiple overlapping sync requests on contacts and they keep overwriting/clearing each others cache state.

You might try clearing the account, and just adding contacts only first, and if they sync completely then turn on the calendar and email. I have no idea if it will work for sure - but worth a try.

[Maxxer]

how sad. I've also seen the issue you reported for my other problem didn't
get any reply.

I'll try with contacts sync only, will report back.
thanks

[Maxxer]

​Just cleaning didn't work. Factory resetting the phone worked... I hope
zarafa will take a look at it someday...

[LiverpoolFCfan]

To be honest I think it is an issue with the client. I don't think it is supposed to issue multiple overlapping requests. But I don't know for sure either way.

Happy you got is sorted out for the moment.

[LiverpoolFCfan]

I have just run into this myself after upgrading my TAB to Android lollipop.

Doing a resync from z-push-admin.php did not help - it just kept looping. However, doing a delete for the user/device actually allowed it to sync up correctly.

z-push-admin.php -a delete -u username - d deviceID

It would be interesting to see if it works for you too.

Still no progress from zarafa in looking at whether they can work around it or not.

[Maxxer]

I always tried -a remove, next time it happens I will try with -a delete and let you know.
thanks

[LiverpoolFCfan]

Sorry, you are right. Should have looked at the command rather than going from memory. It is "remove". The main point I was making is that resync did not work for me - but just doing a remove from z-push-admin.php without removing the account from the device actually allowed it to sync up correctly again.