[horde] Horde 5.1.1 problems with ActiveSync on Android

[Thomas Bühlmann]

Am 17.07.2013 02:34, schrieb 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
Saw the update this morning and tried again with my original adress book.
Everything OK! :)
Thanks for the fix!
--Tom