[horde] ActiveSync syncing contacts fails with " Ungültige ID"

Fri Apr 22 14:12:58 UTC 2011

David Solbach <d at vidsolbach.de> wrote:

>Hi,
>
>I try to use ActiveSync for contacts and calendar with the iOS4 client
>(iPad). 
>Calendar seems to work out of the box. Contacts however are not synced 
>completely. It seems to stop after it synced all he contacts beginning
>wih 'a' 
>(lastname or firstname).
>
>I seperated the activesync log and here is what I get (repeatedly, the
>iPad 
>retries all ofer again).
>
>David
>
>---
>2011-04-22T11:27:30+02:00 INFO: Horde_ActiveSync_Driver_Horde::logon
>attempt 
>for: s at bine-huenecke.de
>2011-04-22T11:27:30+02:00 DEBUG: SQL QUERY: SELECT device_type,
>device_agent, 
>device_ping, device_policykey, device_rwstatus, device_supported FROM 
>horde_activesync_device d INNER JOIN horde_activesync_device_users u ON
>
>d.device_id = u.device_id WHERE u.device_id = ? AND u.device_user = ?
>VALUES: 
>ApplD4048EA6Z38 s at bine-huenecke.de
>2011-04-22T11:27:31+02:00 INFO: Request received from device:
>ApplD4048EA6Z38 
>Supporting protocol version: 2.5
>2011-04-22T11:27:31+02:00 INFO: [ApplD4048EA6Z38] Handling SYNC
>command.
>2011-04-22T11:27:31+02:00 DEBUG: Policykey: 0 verified.
>2011-04-22T11:27:31+02:00 DEBUG: I  <Synchronize>
>2011-04-22T11:27:31+02:00 DEBUG: I   <Folders>
>2011-04-22T11:27:31+02:00 DEBUG: I    <Folder>
>2011-04-22T11:27:31+02:00 DEBUG: I     <FolderType>
>2011-04-22T11:27:31+02:00 DEBUG: I      Contacts
>2011-04-22T11:27:31+02:00 INFO: [ApplD4048EA6Z38] Syncing folder class:
>
>Contacts
>2011-04-22T11:27:31+02:00 DEBUG: I     </FolderType>
>2011-04-22T11:27:31+02:00 DEBUG: I     <SyncKey>
>2011-04-22T11:27:31+02:00 DEBUG: I      {4db14627-fc58-495a-
>b608-7e507f000001}622
>2011-04-22T11:27:31+02:00 DEBUG: I     </SyncKey>
>2011-04-22T11:27:31+02:00 DEBUG: I     <FolderId>
>2011-04-22T11:27:31+02:00 DEBUG: I      Contacts
>2011-04-22T11:27:31+02:00 INFO: [ApplD4048EA6Z38] Folder server id:
>Contacts
>2011-04-22T11:27:31+02:00 DEBUG: I     </FolderId>
>2011-04-22T11:27:31+02:00 DEBUG: I     <DeletesAsMoves/>
>2011-04-22T11:27:31+02:00 DEBUG: Unmatched tagSupported:
>2011-04-22T11:27:31+02:00 DEBUG: I     <GetChanges/>
>2011-04-22T11:27:31+02:00 DEBUG: I     <WindowSize>
>2011-04-22T11:27:31+02:00 DEBUG: I      25
>2011-04-22T11:27:31+02:00 DEBUG: I     </WindowSize>
>2011-04-22T11:27:31+02:00 DEBUG: I    </Folder>
>2011-04-22T11:27:31+02:00 DEBUG: Unmatched tagOptions:
>2011-04-22T11:27:31+02:00 DEBUG: [ApplD4048EA6Z38] Loading state for
>synckey 
>{4db14627-fc58-495a-b608-7e507f000001}622
>2011-04-22T11:27:31+02:00 DEBUG: [ApplD4048EA6Z38] SQL query by 
>Horde_ActiveSync_State:_gc(): SELECT sync_key FROM
>horde_activesync_state 
>WHERE sync_devid = ? AND sync_folderid = ? VALUES: Array
>(
>    [0] => ApplD4048EA6Z38
>    [1] => Contacts
>)
>
>2011-04-22T11:27:31+02:00 DEBUG: [ApplD4048EA6Z38] Found 275 changes
>remaining 
>from previous SYNC.2011-04-22T11:27:31+02:00 DEBUG: O  <Synchronize>
>2011-04-22T11:27:31+02:00 DEBUG: O   <Folders>
>2011-04-22T11:27:31+02:00 DEBUG: O    <Folder>
>2011-04-22T11:27:31+02:00 DEBUG: O     <FolderType>
>2011-04-22T11:27:31+02:00 DEBUG: O     Contacts
>2011-04-22T11:27:31+02:00 DEBUG: O      <FolderType/>
>2011-04-22T11:27:31+02:00 DEBUG: O     <SyncKey>
>2011-04-22T11:27:31+02:00 DEBUG: O     {4db14627-fc58-495a-
>b608-7e507f000001}623
>2011-04-22T11:27:31+02:00 DEBUG: O      <SyncKey/>
>2011-04-22T11:27:31+02:00 DEBUG: O     <FolderId>
>2011-04-22T11:27:31+02:00 DEBUG: O     Contacts
>2011-04-22T11:27:31+02:00 DEBUG: O      <FolderId/>
>2011-04-22T11:27:31+02:00 DEBUG: O     <Status>
>2011-04-22T11:27:31+02:00 DEBUG: O     1
>2011-04-22T11:27:31+02:00 DEBUG: O      <Status/>
>2011-04-22T11:27:31+02:00 DEBUG: O     <MoreAvailable/>
>2011-04-22T11:27:31+02:00 DEBUG: Horde::getMessage(Contacts, )
>2011-04-22T11:27:31+02:00 ERR: Ungültige ID
>2011-04-22T11:27:31+02:00 DEBUG: O     <Folder/>
>2011-04-22T11:27:31+02:00 DEBUG: [ApplD4048EA6Z38] Saving state for
>synckey 
>{4db14627-fc58-495a-b608-7e507f000001}623
>2011-04-22T11:27:31+02:00 DEBUG: O    <Folders/>
>2011-04-22T11:27:31+02:00 DEBUG: O   <Synchronize/>
>2011-04-22T11:27:31+02:00 INFO: User s at bine-huenecke.de logged off
>
>2011-04-22T11:27:31+02:00 DEBUG: Unmatched tagCommands:
>2011-04-22T11:27:31+02:00 DEBUG: I   </Folders>
>2011-04-22T11:27:31+02:00 DEBUG: Unmatched tagFolder:
>2011-04-22T11:27:31+02:00 DEBUG: I  </Synchronize>
>2011-04-22T11:27:31+02:00 INFO: [ApplD4048EA6Z38] Beginning SYNC
>Response.
>2011-04-22T11:27:31+02:00 DEBUG: [ApplD4048EA6Z38] Initializing message
>diff 
>engine for Contacts
>2011-04-22T11:27:31+02:00 DEBUG: [ApplD4048EA6Z38] Returning previously
>found 
>changes.
>2011-04-22T11:27:31+02:00 DEBUG: O  <Synchronize>
>-- 
>Horde mailing list
>Frequently Asked Questions: http://horde.org/faq/
>To unsubscribe, mail: horde-unsubscribe at lists.horde.org

Looks like the contact uids are either not being saved properly or there was some error earlier in the sync that corrupted them.

Can you please reset the client and perform a fresh sync of contacts, and post the entire sync log to a ticket on bugs.horde.org? 

Thanks.
--
Mike
Sent from mobile