[horde] ActiveSync nearly workd - additional infos

Sat Dec 29 21:56:00 UTC 2012

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
-------------- 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/7a29b70a/attachment.bin>