[horde] ActiveSync nearly workd - additional infos

Sat Dec 29 22:09:44 UTC 2012

----- Nachricht von Michael Rubinsky <mrubinsk at horde.org> ---------
   Datum: Sat, 29 Dec 2012 17:02:20 -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,
>>
>> ----- 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.

Thank you. I would be very happy if one of the solvers of this problem  
could help me with their knowledge.
I updated today before I tried activesync so I am at the latest stable  
versions through PEAR.

Horde_ActiveSync           2.0.14
Horde_Core                 2.1.3

Any idea where to start?

Thank you,
Andreas
-------------- next part --------------
A non-text attachment was scrubbed...
Name: smime.p7s
Type: application/pkcs7-signature
Size: 5014 bytes
Desc: S/MIME Signatur
URL: <http://lists.horde.org/archives/horde/attachments/20121229/3a1c7e2a/attachment.bin>