[horde] ActiveSync and personal LDAP Addressbook?

Wed May 4 00:55:42 UTC 2011

Quoting Florian Brandner <florian at brandner.net>:

> Hi!
>
>>> In theory there isn't any reason why this shouldn't work. Was there
>>> any errors present in the Horde log? Please provide the log of a
>>> conversation between client/server where this fails. See
>>> http://wiki.horde.org/ActiveSync for instructions on how to obtain
>>> this log.
>
> Here you go, the output of the Sync-Log for a Contact-Sync:
>
> 2011-04-17T13:36:59+02:00 INFO: Horde_ActiveSync_Driver_Horde::logon
> attempt for: flo
> 2011-04-17T13:37:00+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: androidc239454262 flo
> 2011-04-17T13:37:00+02:00 INFO: Request received from device:
> androidc239454262 Supporting protocol version: 2.5
> 2011-04-17T13:37:00+02:00 INFO: [androidc239454262] Handling SYNC command.
> 2011-04-17T13:37:00+02:00 DEBUG: Policykey:  verified.
> 2011-04-17T13:37:00+02:00 DEBUG: I  <Synchronize>
> 2011-04-17T13:37:00+02:00 DEBUG: I   <Folders>
> 2011-04-17T13:37:00+02:00 DEBUG: I    <Folder>
> 2011-04-17T13:37:00+02:00 DEBUG: I     <FolderType>
> 2011-04-17T13:37:00+02:00 DEBUG: I      Contacts
> 2011-04-17T13:37:00+02:00 INFO: [androidc239454262] Syncing folder
> class: Contacts
> 2011-04-17T13:37:00+02:00 DEBUG: I     </FolderType>
> 2011-04-17T13:37:00+02:00 DEBUG: I     <SyncKey>
> 2011-04-17T13:37:00+02:00 DEBUG: I
> {4daad082-22c4-4797-8cd3-34d051a981c3}1
> 2011-04-17T13:37:00+02:00 DEBUG: I     </SyncKey>
> 2011-04-17T13:37:00+02:00 DEBUG: I     <FolderId>
> 2011-04-17T13:37:00+02:00 DEBUG: I      Contacts
> 2011-04-17T13:37:00+02:00 INFO: [androidc239454262] Folder server id:
> Contacts
> 2011-04-17T13:37:00+02:00 DEBUG: I     </FolderId>
> 2011-04-17T13:37:00+02:00 DEBUG: I     <DeletesAsMoves/>
> 2011-04-17T13:37:00+02:00 DEBUG: Unmatched tagSupported:
> 2011-04-17T13:37:00+02:00 DEBUG: I     <GetChanges/>
> 2011-04-17T13:37:00+02:00 DEBUG: I     <WindowSize>
> 2011-04-17T13:37:00+02:00 DEBUG: I      4
> 2011-04-17T13:37:00+02:00 DEBUG: I     </WindowSize>
> 2011-04-17T13:37:00+02:00 DEBUG: I     <Options>
> 2011-04-17T13:37:00+02:00 DEBUG: I      <Truncation>
> 2011-04-17T13:37:00+02:00 DEBUG: Unmatched tagFilterType:
> 2011-04-17T13:37:00+02:00 DEBUG: I       7
> 2011-04-17T13:37:00+02:00 DEBUG: I      </Truncation>
> 2011-04-17T13:37:00+02:00 DEBUG: I     </Options>
> 2011-04-17T13:37:00+02:00 DEBUG: Unmatched tagRtfTruncation:
> 2011-04-17T13:37:00+02:00 DEBUG: Unmatched tagMIMESupport:
> 2011-04-17T13:37:00+02:00 DEBUG: Unmatched tagMIMETruncation:
> 2011-04-17T13:37:00+02:00 DEBUG: Unmatched tagConflict:
> 2011-04-17T13:37:00+02:00 DEBUG: [androidc239454262] Loading state for
> synckey {4daad082-22c4-4797-8cd3-34d051a981c3}1
> 2011-04-17T13:37:00+02:00 DEBUG: [androidc239454262] SQL query by
> Horde_ActiveSync_State:_gc(): SELECT sync_key FROM
> horde_activesync_state WHERE sync_devid = ? AND sync_folderid = ?
> VALUES: Array
> (
>     [0] => androidc239454262
>     [1] => Contacts
> )
>
> 2011-04-17T13:37:00+02:00 DEBUG: I    </Folder>
> 2011-04-17T13:37:00+02:00 DEBUG: Unmatched tagCommands:
> 2011-04-17T13:37:00+02:00 DEBUG: I   </Folders>
> 2011-04-17T13:37:00+02:00 DEBUG: Unmatched tagFolder:
> 2011-04-17T13:37:00+02:00 DEBUG: I  </Synchronize>
> 2011-04-17T13:37:00+02:00 INFO: [androidc239454262] Beginning SYNC Response.
> 2011-04-17T13:37:00+02:00 DEBUG: [androidc239454262] Initializing
> message diff engine for Contacts
> 2011-04-17T13:37:00+02:00 DEBUG:
> Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 0, 1303040220, 0)
> 2011-04-17T13:37:00+02:00 ERR: Ungültiges Adressbuch: personal_ldap
> 2011-04-17T13:37:00+02:00 DEBUG: [androidc239454262] Found 0 message
> changes, checking for PIM initiated changes.
> 2011-04-17T13:37:00+02:00 DEBUG: [androidc239454262] No PIM changes
> present, returning all messages.
> 2011-04-17T13:37:00+02:00 DEBUG: O  <Synchronize>
> 2011-04-17T13:37:00+02:00 DEBUG: O   <Folders>
> 2011-04-17T13:37:00+02:00 DEBUG: O    <Folder>
> 2011-04-17T13:37:00+02:00 DEBUG: O     <FolderType>
> 2011-04-17T13:37:00+02:00 DEBUG: O     Contacts
> 2011-04-17T13:37:00+02:00 DEBUG: O      <FolderType/>
> 2011-04-17T13:37:00+02:00 DEBUG: O     <SyncKey>
> 2011-04-17T13:37:00+02:00 DEBUG: O
> {4daad082-22c4-4797-8cd3-34d051a981c3}1
> 2011-04-17T13:37:00+02:00 DEBUG: O      <SyncKey/>
> 2011-04-17T13:37:00+02:00 DEBUG: O     <FolderId>
> 2011-04-17T13:37:00+02:00 DEBUG: O     Contacts
> 2011-04-17T13:37:00+02:00 DEBUG: O      <FolderId/>
> 2011-04-17T13:37:00+02:00 DEBUG: O     <Status>
> 2011-04-17T13:37:00+02:00 DEBUG: O     1
> 2011-04-17T13:37:00+02:00 DEBUG: O      <Status/>
> 2011-04-17T13:37:00+02:00 DEBUG: O     <Folder/>
> 2011-04-17T13:37:00+02:00 DEBUG: O    <Folders/>
> 2011-04-17T13:37:00+02:00 DEBUG: O   <Synchronize/>
> 2011-04-17T13:37:00+02:00 INFO: User flo logged off
>
>
> In the Horde-Log I get this error:
>
> 2011-04-17T13:43:43+02:00 ERR: HORDE [turba] Verbindung fehlgeschlagen:
> (53) Server is unwilling to perform [pid 22474 on line 99 of
> "/var/www_horde/turba/lib/Driver/Ldap.php"]
>
>
> The Address-Book "personal_ldap" is used by IMP and Kronolith, without

Getting caught up on email after being out of the loop for a bit.  
Honestly don't remember where we left this, or if this has already  
been responded to - but this might be due to issues related to  
sessions being disabled for ActiveSync requests. I have committed  
changes in Git to address this.

mike

The Horde Project (www.horde.org)
mrubinsk at horde.org