[horde] ActiveSync nearly workd - additional infos

[Michael Rubinsky]

Quoting Andreas Mauser <andreas at mauser.info>> Hi,
>
> ----- Nachricht von Michael Rubinsky <mrubinsk at horde.org> ---------
>   Datum: Sat, 29 Dec 2012 16:39:38 -0500
>     Von: Michael Rubinsky <mrubinsk at horde.org>
> Betreff: Re: [horde] ActiveSync nearly workd - additional infos
>      An: Andreas Mauser <andreas at mauser.info>
>      Cc: horde at lists.horde.org
>
>
>> Quoting Andreas Mauser <andreas at mauser.info>> Hi Mike,
>>>
>>> ----- Nachricht von mrubinsk at horde.org ---------
>>> Datum: Sat, 29 Dec 2012 15:52:23 -0500
>>>   Von: mrubinsk at horde.org
>>> Betreff: Re: [horde] ActiveSync nearly workd - additional infos
>>>    An: Andreas Mauser <andreas at mauser.info>
>>>    Cc: horde at lists.horde.org
>>>
>>>
>>>> Quoting Andreas Mauser <andreas at mauser.info>>
>>>>> Hi,
>>>>>
>>>>> I can not see any device in Horde activesync user settings,  
>>>>> altough the login and account verifying was successful. It  
>>>>> 'wants' to sync, but cant.
>>>>>
>>>>> I also would like to supply the full activesync log,  
>>>>> unfortunately nothing is logged into the file I set in Horde  
>>>>> activesync log settings.
>>>>>
>>>>> Any hint how to make this log file work? Rights are on 777 for  
>>>>> this file and it relies in /var/www where apache has access to.
>>>>>
>>>>> Thank you,
>>>>> Andreas
>>>>>
>>>> Make sure the logging is set to a path and not a file.
>>>
>>> Thank you. I tried a directory with and without a traling slash,  
>>> both does not bring me any file in the log directory (which is  
>>> /var/www/html/ActiveSyncLog1 which I set in Horde)
>>>
>>> Horde itself is set to DEBUG and looging to syslog.
>>>
>>> Thank you for helping,
>>> Andreas
>>>
>> ..and this directory exists?
>
> Exists and is writeable:
>
>  ls -hal /var/www/html/
> insgesamt 52K
> drwxrwx---. 13 apache apache 4,0K 29. Dez 22:49 .
> drwxr-xr-x.  9 root   root   4,0K 29. Mai 2012  ..
> drwxr-xr-x.  2 apache apache 4,0K 29. Dez 22:21 ActiveSyncLog1
>
>
> and in my apache vhost I have:
>
> ## Horde ActiveSync
>
> Alias /Microsoft-Server-ActiveSync  
> "/var/www/html/mauser.info/mailoffice/rpc.php"
> Alias /autodiscover/autodiscover.xml  
> "/var/www/html/mauser.info/mailoffice/rpc.php"
> Alias /AutoDiscover/AutoDiscover.xml  
> "/var/www/html/mauser.info/mailoffice/rpc.php"
>
> RewriteEngine On
> RewriteRule ^/Microsoft-Server-ActiveSync /mailoffice/rpc.php [PT,L,QSA]
>
> RewriteRule .* - [E=HTTP_MS_ASPROTOCOLVERSION:%{HTTP:Ms-Asprotocolversion}]
> RewriteRule .* - [E=HTTP_X_MS_POLICYKEY:%{HTTP:X-Ms-Policykey}]
> RewriteRule .* - [E=HTTP_AUTHORIZATION:%{HTTP:Authorization}]
>
> Error 401. Cant get that away. But I got the log to work.
>
> cat /var/www/html/ActiveSyncLog1/SEC18AE1A2A432B3.txt
> 2012-12-29T22:54:27+01:00 INFO: [32556]  
> Horde_Core_ActiveSync_Driver::authenticate() attempt for andreas
> 2012-12-29T22:54:27+01:00 DEBUG: [SEC18AE1A2A432B3] FOLDERSYNC  
> request received for user andreas
> 2012-12-29T22:54:27+01:00 DEBUG: [SEC18AE1A2A432B3] loadDeviceInfo: andreas
> 2012-12-29T22:54:27+01:00 INFO: Request being handled for device:  
> SEC18AE1A2A432B3 Supporting protocol version: 12.0
> 2012-12-29T22:54:27+01:00 INFO: [SEC18AE1A2A432B3] Handling  
> FOLDERSYNC command.
> 2012-12-29T22:54:27+01:00 DEBUG: [SEC18AE1A2A432B3] Checking  
> policykey for device: 0 user: andreas
> 2012-12-29T22:54:27+01:00 DEBUG: Policykey: 0 verified.
> 2012-12-29T22:54:27+01:00 DEBUG: I  <FolderHierarchy:FolderSync>
> 2012-12-29T22:54:27+01:00 DEBUG: I   <FolderHierarchy:SyncKey>
> 2012-12-29T22:54:27+01:00 DEBUG: I    0
> 2012-12-29T22:54:27+01:00 DEBUG: I   </FolderHierarchy:SyncKey>
> 2012-12-29T22:54:27+01:00 DEBUG:  
> [Horde_ActiveSync::handleFolderSync] syncKey: 0
> 2012-12-29T22:54:27+01:00 DEBUG: [32556] Resetting device state.
> 2012-12-29T22:54:27+01:00 DEBUG: Replacing SYNC_CACHE entry for user  
> andreas and device SEC18AE1A2A432B3:  
> a:12:{s:18:"confirmed_synckeys";a:0:{}s:17:"lasthbsyncstarted";b:0;s:17:"lastsyncendnormal";b:0;s:9:"lastuntil";b:0;s:9:"timestamp";s:10:"1356818067";s:4:"wait";b:0;s:10:"hbinterval";b:0;s:7:"folders";a:0:{}s:9:"hierarchy";b:0;s:11:"collections";a:0:{}s:13:"pingheartbeat";b:0;s:14:"synckeycounter";a:0:{}}
> 2012-12-29T22:54:27+01:00 DEBUG: I  </FolderHierarchy:FolderSync>
> 2012-12-29T22:54:27+01:00 DEBUG:  
> [Horde_ActiveSync::handleFolderSync] newSyncKey:  
> {50df6693-75c0-4577-b147-7f2c0a001802}1
> 2012-12-29T22:54:27+01:00 DEBUG: [32556] Initializing folder diff engine
> 2012-12-29T22:54:27+01:00 DEBUG: [32556]  
> Horde_Core_ActiveSync_Driver::getFolderList()
> 2012-12-29T22:54:27+01:00 DEBUG: [32556] Polling  
> Horde_Core_ActiveSync_Driver::_getMailFolders()
> 2012-12-29T22:54:28+01:00 ERR: [32556] Problem loading mail folders  
> from IMAP server:
> 2012-12-29T22:54:28+01:00 ERR:
> 2012-12-29T22:54:28+01:00 DEBUG: [32556] No folder changes found.
> 2012-12-29T22:54:28+01:00 DEBUG: O  <FolderHierarchy:FolderSync>
> 2012-12-29T22:54:28+01:00 DEBUG: O   <FolderHierarchy:Status>
> 2012-12-29T22:54:28+01:00 DEBUG: O    1
> 2012-12-29T22:54:28+01:00 DEBUG: O   <FolderHierarchy:Status/>
> 2012-12-29T22:54:28+01:00 DEBUG: O   <FolderHierarchy:SyncKey>
> 2012-12-29T22:54:28+01:00 DEBUG: O    0
> 2012-12-29T22:54:28+01:00 DEBUG: O   <FolderHierarchy:SyncKey/>
> 2012-12-29T22:54:28+01:00 DEBUG: O   <FolderHierarchy:Changes>
> 2012-12-29T22:54:28+01:00 DEBUG: O    <FolderHierarchy:Count>
> 2012-12-29T22:54:28+01:00 DEBUG: O     0
> 2012-12-29T22:54:28+01:00 DEBUG: O    <FolderHierarchy:Count/>
> 2012-12-29T22:54:28+01:00 DEBUG: O   <FolderHierarchy:Changes/>
> 2012-12-29T22:54:28+01:00 DEBUG: O  <FolderHierarchy:FolderSync/>
> 2012-12-29T22:54:28+01:00 INFO: [32556] User andreas logged off
>
>
> Thank you,
> Andreas
>
This is the same error that is being discussed in another thread on  
the dev@ list...and was reported fixed by at least one person with the  
latest releases. I haven't been able to reproduce though.

Mike
Sent from mobile

Andreas Mauser <andreas at mauser.info> wrote: