[horde] 500 error with activesync

Sat Jun 15 20:02:23 UTC 2013

----- Nachricht von Michael J Rubinsky <mrubinsk at horde.org> ---------
      Datum: Sat, 15 Jun 2013 11:33:23 -0400
        Von: Michael J Rubinsky <mrubinsk at horde.org>
Antwort an: mrubinsk at horde.org
    Betreff: Re: [horde] 500 error with activesync
         An: horde at lists.horde.org

> Quoting Andreas Mauser <andreas at mauser.info>:
>
>> Hi,
>>
>> I found some problems in my syslog, and hoped we had this problem already?
>> Could not find it in the mailing list.
>>
>> 2013-06-15T17:00:05.372769+02:00 webster HORDE: [horde] Buffer contents:
>>  [pid 3624 on line 160 of "/usr/share/pear/Horde/Rpc/ActiveSync.php"]Jun
>> 15 17:00:05 webster HORDE: [horde] Buffer contents:  [pid 3624 on line 160
>> of
>> "/usr/share/pear/Horde/Rpc/ActiveSync.php"]2013-06-15T17:00:05.393054+02:00
>> webster HORDE: [horde] Returning HTTP 500 while handling Sync command. [pid
>> 3580 on line 156 of "/var/www/html/mauser.info/mailoffice/rpc.php"]Jun 15
>> 17:00:05 webster HORDE: [horde] Returning HTTP 500 while handling Sync
>> command. [pid 3580 on line 156 of
>> "/var/www/html/mauser.info/mailoffice/rpc.php"]2013-06-15T17:00:05.393382+02:00
>> webster HORDE: [horde] Error in communicating with ActiveSync server:
>> Horde_Log_Handler_Stream: Unable to write to stream [pid 3580 on line 160
>> of "/usr/share/pear/Horde/Rpc/ActiveSync.php"]Jun 15 17:00:05 webster
>> HORDE: [horde] Error in communicating with ActiveSync server:
>> Horde_Log_Handler_Stream: Unable to write to stream [pid 3580 on line 160
>> of "/usr/share/pear/Horde/Rpc/ActiveSync.php"]
>>
>> The website https://www.testexchangeconnectivity.com returns:
>>
>> Fehler beim Testen des Befehls „FolderSync“. Weitere Details Die
>> Antwort „HTTP 403 Verboten“ wurde empfangen. Die Antwort scheint von
>> Unknown zu stammen. Antworttext: <!DOCTYPE HTML PUBLIC "-//IETF//DTD HTML
>> 2.0//EN"> <html><head> <title>403 Forbidden</title> </head><body>
>> <h1>Forbidden</h1> <p>You don't have permission to access
>> /mailoffice/rpc.php on this server.</p> </body></html> 
>>
>> But the file is accessible and has rights:
>>
>> -rwxrwxrwx. 1 apache apache 5,7K  5. Jun 05:43
>> /var/www/html/mauser.info/mailoffice/rpc.php
>>
>> And I can login to Horde and use it properly.
>>
>> I believe it happened after an upgrade of the system or pear, I cant tell
>> right now.
>>
>> Any idea about that?
>
> Nope. In order to have received the 500, you *must* have had access  
> to rpc.php. No idea what is going on with the connectivity tester.  
> The 500, itself, just means there was some general error in  
> processing the request (depending on the version of EAS a 500 is  
> returned or a certain wbxml status code). The actual synclog should  
> have more information as to what actually happened. Receiving this  
> occasionally is not an issue. If you receive these constantly and/or  
> sync is not working then something is obviously wrong.

yes, I get this error regularily. now I get some more, I dont know if  
the both are connected:

2013-06-15T21:23:08.694123+02:00 webster HORDE: [horde] SQL QUERY  
FAILED: SQLSTATE[23000]: Integrity constraint violation: 1062  
Duplicate entry '{51bcbe8f-36f0-4624-933f-15430a001802}5' for key  
'PRIMARY'  #012#011INSERT INTO horde_activesync_state (sync_key,  
sync_data, sync_devid,#012#011  sync_time, sync_folderid, sync_user,  
sync_pending) VALUES#012#011   
('{51bcbe8f-36f0-4624-933f-15430a001802}5',#012#011   
'O:34:"Horde_ActiveSync_Folder_Collection":3:{s:10:"
Jun 15 21:23:08 webster HORDE: [horde] SQL QUERY FAILED:  
SQLSTATE[23000]: Integrity constraint violation: 1062 Duplicate entry  
'{51bcbe8f-36f0-4624-933f-15430a001802}5' for key 'PRIMARY'   
#012#011INSERT INTO horde_activesync_state (sync_key, sync_data,  
sync_devid,#012#011  sync_time, sync_folderid, sync_user,  
sync_pending) VALUES#012#011   
('{51bcbe8f-36f0-4624-933f-15430a001802}5',#012#011   
'O:34:"Horde_ActiveSync_Folder_Collection":3:{s:10:"

2013-06-15T21:24:16.812989+02:00 webster HORDE: [horde] Returning HTTP  
500 while handling Sync command. [pid 5913 on line 156 of  
"/var/www/html/mauser.info/mailoffice/rpc.php"]
Jun 15 21:24:16 webster HORDE: [horde] Returning HTTP 500 while  
handling Sync command. [pid 5913 on line 156 of  
"/var/www/html/mauser.info/mailoffice/rpc.php"]
2013-06-15T21:24:16.813291+02:00 webster HORDE: [horde] Error in  
communicating with ActiveSync server: Horde_Log_Handler_Stream: Unable  
to write to stream [pid 5913 on line 160 of  
"/usr/share/pear/Horde/Rpc/ActiveSync.php"]

I tested around and found that TASKS, NOTES, and MAIL are being synced  
correctly.
Its just that the calender on my Android dont get synced. And as I  
found out only new entries are not synced, it seems.

so SQL says I have some problem entry? How can I non-SQL user correct  
this, because it just gives me a number (1062 Duplicate entry  
'{51bcbe8f-36f0-4624-933f-15430a001802}5' ?)

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