[horde] Horde 5.1.1 problems with ActiveSync on Android

[Daniel Ritter]

Am 17.07.2013 01:22, schrieb simon at simonandkate.net:
>>>>>>>>> I upgraded from 5.0.1 to 5.1.1 today.
>>>>>>>>> Now Activesync doesn't work anymore as expected. Mail syncs
>>>>>>>>> fine, contacts and calendars do not.
>>>>>>>>> I lost all contacts and calendars on the android devices. They
>>>>>>>>> are still available in Horde, but don't sync.
>>>>>> Same Problem here. Everything syncs except the Contacts. This
>>>>>> used  to work before.
>>>>>>>>> I am using two different Android clients for testing, both
>>>>>>>>> running different versions of Cyanogen Mod.
>>>>>>>>> They used to sync fine with 5.0.1.
>>>>>>>> Cannot reproduce. Works fine for me with numerous versions of
>>>>>>>> Android, both stock and CM.
>>>>>>>>
>>>>>>>> Any errors in the server log, horde log? What does the
>>>>>>>> activesync log show?
>>>>>> Not sure if this is the same problem;
>>>>>> I tried to sync a turba adressbook with only one contact via activesync.
>>>>>> The synclog stops after the provisioning is done. Nothing more is logged.
>>>>>> I'm syncing to a xperia z with android 4.2.2.
>>>>>>> I investigated a bit further.
>>>>>>> Wiped my android, and just enabled contacts sync.
>>>>>>> This happens when trying to sync contacts:
>>>>>>>
>>>>>>> http://pastebin.com/AF38BYS2
>>>>>>>
>>>>>>> Maybe this error is relevant?
>>>>>>> (Line 180)
>>>>>>> 2013-07-10T15:10:51+00:00 NOTICE: [8428] Error saving state for
>>>>>>> synckey {51dd790e-4830-4ca4-91e6-20ebc0a80102}2: QUERY FAILED:
>>>>>>> Duplicate entry '{51dd790e-4830-4ca4-91e6-20ebc0a80102}2' for
>>>>>>> key  'PRIMARY'
>>>>>>>
>>>>>>> This is how a request in Apache looks like:
>>>>>>> Android ActiveSync Client is Android/4.1.2-EAS-1.3
>>>>>>>
>>>>>>> 62.143.158.113 - myserver.dyndns.org\\mailbox
>>>>>>> [10/Jul/2013:17:10:38 +0200] "POST
>>>>>>> /Microsoft-Server-ActiveSync?Cmd=Sync&User=myserver.dyndns.org%5Cmailbox&DeviceId=androidc1491253035&DeviceType=Android HTTP/1.1" 200 585 "-"
>>>>>>> "Android/4.1.2-EAS-1.3"
>>>>>>>
>>>>>>> Because i completely wiped my phone I think it must be a change
>>>>>>> in Horde from 5.0.1 to 5.1.1 that broke it somehow for me.
>>>>>>> It worked great for a long time before.
>>>>>>>
>>>>>>> Thanks for any input.
>>>>>> For the moment i sync my contacts via the new CardDav Service.
>>>>>> This works perfectly.
>>>
>>> Happening to me also.
>>>
>>> I reset the device's AS connection as part of checking something
>>> else, and Contacts no longer Sync at all to the Android device.
>>> Still sync to iPad OK. Email, tasks, calendar appear to sync fine.
>>>
>>> I did a second delete / reset on the phone's AS horde connection,
>>> and re-enabled with just Contacts, and still nothing.
>>>
>>> Horde error log shows an SQL QUERY FAILED error for the INSERT INTO
>>> horde_activesync_state, followed by an enormous list of
>>> \"NewMessage\" entries for what looks like each of my contacts. Here
>>> is an extract:
>>>
>>> 2013-07-16T01:33:36+00:00 ERR: HORDE [horde] SQL QUERY FAILED:
>>> SQLSTATE[23000]: Integrity constraint violation: 1062 Duplicate
>>> entry '{51e498a8-a9e4-439e-a493-0b3dc0a801e6}2' for key 'PRIMARY'
>>>     INSERT INTO horde_activesync_state (sync_key, sync_data, sync_devid,
>>>       sync_time, sync_folderid, sync_user, sync_pending) VALUES
>>>       ('{51e498a8-a9e4-439e-a493-0b3dc0a801e6}2',
>>>       'O:34:"Horde_ActiveSync_Folder_Collection":4:{s:15:"haveInitialSync";b
>>>       :1;s:10:"*_status";a:0:{}s:12:"*_serverid";s:10:"@Contacts@";s:9:"
>>>       *_class";s:8:"Contacts";}', 'SEC10D1C297BE813', 1373938414,
>>>       '@Contacts@', 'simon',
>>>       'a:361:{i:0;a:3:{s:2:\"id\";s:60:\"20130311055925.-LOYZLAqDHeVWI5o1ubL
>>>       uw5 at mail.simonandkate.net\";s:4:\"type\";s:6:\"change\";s:5:\"flags\";
>>>       s:10:\"NewMessage\";}i:1;a:3:{s:2:\"id\";s:62:\"20130129094340.XFDFhY9
>>>       RNmOQJmbNuE1bJQ4 at server.simonandkate.net\";s:4:\"type\";s:6:\"change\"
>>>       ;s:5:\"flags\";s:10:\"NewMessage\";}i:2;a:3:{s:2:\"id\";s:62:\"2013012
>>>       9094340.nrhEoNigM5oS60dsxbLT6A2 at server.simonandkate.net\";s:4:\"type\"
>>>       ;s:6:\"change\";s:5:\"flags\";s:10:\"NewMessage\";}i:3;a:3:{s:2:\"id\"
>>>       ;s:62:\"20130129094341.XLaIPJnNhrOyHhJ6bJ-e7Q2 at server.simonandkate.net
>>>
>>> The phone AS debug log shows what appears to be the first 6 entries
>>> sync to the device (but which never actually show on the device),
>>> then stops with 361 to go with this:
>>>
>>>
> Snip
>
>> Possibly Bug: 12443
>> -- 
>> mike
>>
> Yes, fixed by the last AS upgrade, installed this morning. Thanks Mike.
>
> Simon.
>
Works here too. Thanks for the quick fix!
Daniel