[horde] 500 error with activesync

Andreas Mauser andreas at mauser.info
Sat Jun 15 20:54:31 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.

And this is the synclog...
Seems after the last synched entry is an error?

2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:Body>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         Pan y Tango
Milonga in der Brotfabrik

Jeden ersten Freitag im Monat
ab 21:30 - 4:00 Uhr
DJ: Karin Betz
Eintritt: 6,-€

Brotfabrik / Frankfurt am Main
Bachmannstr. 2-4
U-Bahn U6/U7 in Richtung Heerstraße/Hausen
Haltestelle: Industriehof
Brotfabrik: 069 24790800
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:Body>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:BodyTruncated>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         0
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:BodyTruncated>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O       </Data>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O      </Add>
2013-06-15T22:49:36+02:00 INFO: [15026] Updating state during change
2013-06-15T22:49:36+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getMessage(@Calendar@,  
20130615214241.Baqy0ln7p2UB8JMgMzr8Aw1 at mauser.info)
2013-06-15T22:49:36+02:00 DEBUG: [15026] O      <Add>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O       <ServerEntryId>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         
20130615214241.Baqy0ln7p2UB8JMgMzr8Aw1 at mauser.info
2013-06-15T22:49:36+02:00 DEBUG: [15026] O       </ServerEntryId>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O       <Data>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:Timezone>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O          
xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:Timezone>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:DtStamp>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         20130615T204936Z
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:DtStamp>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:StartTime>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         20130614T220000Z
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:StartTime>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:Subject>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         test2
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:Subject>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:UID>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O          
32303133303631353231343234312E42617179306C6E3770325542384A4D674D7A7238417731406D61757365722E696E666F
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:UID>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:Location>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         erfer
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:Location>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:EndTime>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         20130615T220000Z
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:EndTime>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:Sensitivity>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         0
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:Sensitivity>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:BusyStatus>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         2
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:BusyStatus>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:AllDayEvent>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         1
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:AllDayEvent>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:Reminder>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         15
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:Reminder>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:MeetingStatus>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         0
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:MeetingStatus>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:Body>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         sdfdgsdgd
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:Body>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:BodyTruncated>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         0
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:BodyTruncated>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O       </Data>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O      </Add>
2013-06-15T22:49:36+02:00 INFO: [15026] Updating state during change
2013-06-15T22:49:36+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getMessage(@Calendar@,  
20130615220251.SxsF5W1hrwcY-tqmmrnIDw7 at mauser.info)
2013-06-15T22:49:36+02:00 DEBUG: [15026] O      <Add>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O       <ServerEntryId>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         
20130615220251.SxsF5W1hrwcY-tqmmrnIDw7 at mauser.info
2013-06-15T22:49:36+02:00 DEBUG: [15026] O       </ServerEntryId>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O       <Data>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:Timezone>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O          
xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:Timezone>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:DtStamp>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         20130615T204936Z
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:DtStamp>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:StartTime>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         20130615T220000Z
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:StartTime>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:Subject>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         huga
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:Subject>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:UID>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O          
32303133303631353232303235312E5378734635573168727763592D74716D6D726E49447737406D61757365722E696E666F
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:UID>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:EndTime>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         20130615T230000Z
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:EndTime>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:Sensitivity>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         0
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:Sensitivity>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:BusyStatus>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         2
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:BusyStatus>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:Reminder>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         15
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:Reminder>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:MeetingStatus>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         0
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:MeetingStatus>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        <POOMCAL:BodyTruncated>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O         0
2013-06-15T22:49:36+02:00 DEBUG: [15026] O        </POOMCAL:BodyTruncated>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O       </Data>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O      </Add>
2013-06-15T22:49:36+02:00 INFO: [15026] Updating state during change
2013-06-15T22:49:36+02:00 INFO: [15026] Exported maxItems of messages  
(4) - more available.
2013-06-15T22:49:36+02:00 DEBUG: [15026] O     </Commands>
2013-06-15T22:49:36+02:00 INFO: [15026] Saving state: Array
(
     [0] => {51bcd09f-5710-45ef-861b-2d560a001802}7
     [1] => Horde_Db_Value_Binary Object
         (
             [_value:protected] =>  
O:34:"Horde_ActiveSync_Folder_Collection":3:{s:10:"*_status";a:0:{}s:12:"*_serverid";s:10:"@Calendar@";s:9:"*_class";s:8:"Calendar";}
         )

     [2] => ANDROID1368223989028
     [3] => 1371329376
     [4] => @Calendar@
     [5] => andreas
     [6] => 8
)

2013-06-15T22:49:36+02:00 NOTICE: [15026] Error saving state for  
synckey {51bcd09f-5710-45ef-861b-2d560a001802}7: SQLSTATE[23000]:  
Integrity constraint violation: 1062 Duplicate entry  
'{51bcd09f-5710-45ef-861b-2d560a001802}7' for key 'PRIMARY' - removing  
previous sync state and trying again.
2013-06-15T22:49:36+02:00 DEBUG: [15026] O    </Folder>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O   </Folders>
2013-06-15T22:49:36+02:00 DEBUG: [15026] O  </Synchronize>
2013-06-15T22:49:36+02:00 INFO: [15026] Replacing SYNC_CACHE entry for  
user andreas and device ANDROID1368223989028:  
a:11:{s:18:"confirmed_synckeys";a:1:{s:39:"{51bcd09f-5710-45ef-861b-2d560a001802}7";b:1;}s:17:"lasthbsyncstarted";i:1371329367;s:17:"lastsyncendnormal";b:0;s:9:"timestamp";s:10:"1371329376";s:4:"wait";b:0;s:10:"hbinterval";s:3:"470";s:7:"folders";a:53:{s:8:"f7fa204e";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:16:"

some folders synching...
...
..
a284-47d6-a71d-29140a001802}1";s:11:"collections";a:3:{s:8:"f317812c";a:12:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:10:"truncation";i:0;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";a:0:{}s:8:"serverid";s:5:"INBOX";s:11:"lastsynckey";s:40:"{51bcd09e-d564-43fe-a1dc-2d7c0a001802}52";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:8:"pingable";b:1;}s:10:"@Contacts@";a:12:{s:5:"class";s:8:"Contacts";s:10:"windowsize";s:1:"4";s:10:"truncation";s:1:"7";s:11:"mimesupport";i:0;s:14:"mimetruncation";i:8;s:8:"conflict";i:1;s:9:"bodyprefs";a:0:{}s:8:"serverid";s:10:"@Contacts@";s:11:"lastsynckey";s:40:"{51bcd09f-0698-454f-8080-2d440a001802}58";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:8:"pingable";b:1;}s:10:"@Calendar@";a:12:{s:5:"class";s:8:"Calendar";s:10:"windowsize";s:1:"4";s:10:"truncation";s:1:"7";s:11:"mimesupport";i:0;s:14:"mimetruncation";i:8;s:8:"conflict";i:1;s:9:"bodyprefs";a:0:{}s:8:"serverid";s:10:"@Calend
ar@";s:11:"lastsynckey";s:39:"{51bcd09f-5710-45ef-861b-2d560a001802}7";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:8:"pingable";b:0;}}s:13:"pingheartbeat";b:0;s:14:"synckeycounter";a:3:{s:8:"f317812c";a:1:{s:40:"{51bcd09e-d564-43fe-a1dc-2d7c0a001802}51";i:1;}s:10:"@Contacts@";a:1:{s:40:"{51bcd09f-0698-454f-8080-2d440a001802}57";i:1;}s:10:"@Calendar@";a:1:{s:39:"{51bcd09f-5710-45ef-861b-2d560a001802}6";i:6;}}}
2013-06-15T22:49:36+02:00 INFO: [15026] User andreas logged off
2013-06-15T22:49:37+02:00 INFO: [13650] SyncCache collections refreshed.
2013-06-15T22:49:37+02:00 INFO: [13650] Refreshing f317812c from the cache.
2013-06-15T22:49:37+02:00 INFO: [13650] Refreshing @Contacts@ from the cache.
2013-06-15T22:49:37+02:00 INFO: [13650] Initializing state for  
collection: INBOX, synckey: {51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:49:37+02:00 INFO: [13650] Loading state for synckey  
{51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:49:37+02:00 INFO: [13650] Initializing message diff  
engine for f317812c (INBOX)
2013-06-15T22:49:37+02:00 INFO: [13650]  
Horde_Core_ActiveSync_Driver::getServerChanges(INBOX, 1371328775,  
1371329377, 0, 1)
2013-06-15T22:49:37+02:00 INFO: [13650] Found 0 message changes in f317812c.
2013-06-15T22:49:37+02:00 INFO: [13650] Initializing state for  
collection: @Contacts@, synckey:  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:49:37+02:00 INFO: [13650] Loading state for synckey  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:49:37+02:00 INFO: [13650] Initializing message diff  
engine for @Contacts@ (@Contacts@)
2013-06-15T22:49:37+02:00 INFO: [13650]  
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 1371328790,  
1371329377, 0, 1)
2013-06-15T22:49:37+02:00 INFO: [13650] Found 0 message changes in @Contacts at .
2013-06-15T22:49:37+02:00 INFO: [13650] Sleeping for 5 seconds.
2013-06-15T22:49:38+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::authenticate() attempt for andreas
2013-06-15T22:49:38+02:00 DEBUG: [15026] PING request received for  
user andreas
2013-06-15T22:49:38+02:00 INFO: [15026] Device entry exists for  
ANDROID1368223989028, updating userAgent and version.
2013-06-15T22:49:38+02:00 INFO: [15026] Request being handled for  
device: ANDROID1368223989028 Supporting protocol version: 2.5
2013-06-15T22:49:38+02:00 INFO: [15026] Handling PING command received  
at timestamp: 1371329378.
2013-06-15T22:49:38+02:00 DEBUG: [15026] I  <Ping:Ping>
2013-06-15T22:49:38+02:00 DEBUG: [15026] I   <Ping:HeartbeatInterval>
2013-06-15T22:49:38+02:00 DEBUG: [15026] I     470
2013-06-15T22:49:38+02:00 DEBUG: [15026] I   </Ping:HeartbeatInterval>
2013-06-15T22:49:38+02:00 DEBUG: [15026] I   <Ping:Folders>
2013-06-15T22:49:38+02:00 DEBUG: [15026] I    <Ping:Folder>
2013-06-15T22:49:38+02:00 DEBUG: [15026] I     <Ping:ServerEntryId>
2013-06-15T22:49:38+02:00 DEBUG: [15026] I       f317812c
2013-06-15T22:49:38+02:00 DEBUG: [15026] I     </Ping:ServerEntryId>
2013-06-15T22:49:38+02:00 DEBUG: [15026] I     <Ping:FolderType>
2013-06-15T22:49:38+02:00 DEBUG: [15026] I       Email
2013-06-15T22:49:38+02:00 DEBUG: [15026] I     </Ping:FolderType>
2013-06-15T22:49:38+02:00 DEBUG: [15026] I    </Ping:Folder>
2013-06-15T22:49:38+02:00 INFO: [15026] Obtained synckey for  
collection f317812c from cache: {51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:49:38+02:00 INFO: [15026] Collection added to collection  
handler: collection: INBOX, synckey:  
{51bcd09e-d564-43fe-a1dc-2d7c0a001802}52.
2013-06-15T22:49:38+02:00 DEBUG: [15026] I    <Ping:Folder>
2013-06-15T22:49:38+02:00 DEBUG: [15026] I     <Ping:ServerEntryId>
2013-06-15T22:49:38+02:00 DEBUG: [15026] I       @Contacts@
2013-06-15T22:49:38+02:00 DEBUG: [15026] I     </Ping:ServerEntryId>
2013-06-15T22:49:38+02:00 DEBUG: [15026] I     <Ping:FolderType>
2013-06-15T22:49:38+02:00 DEBUG: [15026] I       Contacts
2013-06-15T22:49:38+02:00 DEBUG: [15026] I     </Ping:FolderType>
2013-06-15T22:49:38+02:00 DEBUG: [15026] I    </Ping:Folder>
2013-06-15T22:49:38+02:00 INFO: [15026] Obtained synckey for  
collection @Contacts@ from cache:  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:49:38+02:00 INFO: [15026] Collection added to collection  
handler: collection: @Contacts@, synckey:  
{51bcd09f-0698-454f-8080-2d440a001802}58.
2013-06-15T22:49:38+02:00 DEBUG: [15026] I   </Ping:Folders>
2013-06-15T22:49:38+02:00 INFO: Setting collection INBOX (f317812c) PINGABLE.
2013-06-15T22:49:38+02:00 INFO: Setting collection @Contacts@  
(@Contacts@) PINGABLE.
2013-06-15T22:49:38+02:00 INFO: UNSETTING collection @Calendar@  
(@Calendar@) PINGABLE flag.
2013-06-15T22:49:38+02:00 DEBUG: [15026] I  </Ping:Ping>
2013-06-15T22:49:38+02:00 INFO: Waiting for changes for 470 seconds
2013-06-15T22:49:38+02:00 INFO: [15026] Replacing SYNC_CACHE entry for  
user andreas and device ANDROID1368223989028:  
a:11:{s:18:"confirmed_synckeys";a:1:{s:39:"{51bcd09f-5710-45ef-861b-2d560a001802}7";b:1;}s:17:"lasthbsyncstarted";i:1371329378;s:17:"lastsyncendnormal";b:0;s:9:"timestamp";s:10:"1371329378";s:4:"wait";b:0;s:10:"hbinterval";s:3:"470";s:7:"folders";a:53:{s:8:"f7fa204e";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:16:"

some folders synching....
....
....
a284-47d6-a71d-29140a001802}1";s:11:"collections";a:3:{s:8:"f317812c";a:12:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:10:"truncation";i:0;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";a:0:{}s:8:"serverid";s:5:"INBOX";s:11:"lastsynckey";s:40:"{51bcd09e-d564-43fe-a1dc-2d7c0a001802}52";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:8:"pingable";b:1;}s:10:"@Contacts@";a:12:{s:5:"class";s:8:"Contacts";s:10:"windowsize";s:1:"4";s:10:"truncation";s:1:"7";s:11:"mimesupport";i:0;s:14:"mimetruncation";i:8;s:8:"conflict";i:1;s:9:"bodyprefs";a:0:{}s:8:"serverid";s:10:"@Contacts@";s:11:"lastsynckey";s:40:"{51bcd09f-0698-454f-8080-2d440a001802}58";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:8:"pingable";b:1;}s:10:"@Calendar@";a:12:{s:5:"class";s:8:"Calendar";s:10:"windowsize";s:1:"4";s:10:"truncation";s:1:"7";s:11:"mimesupport";i:0;s:14:"mimetruncation";i:8;s:8:"conflict";i:1;s:9:"bodyprefs";a:0:{}s:8:"serverid";s:10:"@Calend
ar@";s:11:"lastsynckey";s:39:"{51bcd09f-5710-45ef-861b-2d560a001802}7";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:8:"pingable";b:0;}}s:13:"pingheartbeat";b:0;s:14:"synckeycounter";a:3:{s:8:"f317812c";a:1:{s:40:"{51bcd09e-d564-43fe-a1dc-2d7c0a001802}51";i:1;}s:10:"@Contacts@";a:1:{s:40:"{51bcd09f-0698-454f-8080-2d440a001802}57";i:1;}s:10:"@Calendar@";a:1:{s:39:"{51bcd09f-5710-45ef-861b-2d560a001802}6";i:6;}}}
2013-06-15T22:49:38+02:00 INFO: [15026] Initializing state for  
collection: INBOX, synckey: {51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:49:38+02:00 INFO: [15026] Loading state for synckey  
{51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:49:38+02:00 INFO: [15026] Initializing message diff  
engine for f317812c (INBOX)
2013-06-15T22:49:38+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(INBOX, 1371328775,  
1371329378, 0, 1)
2013-06-15T22:49:38+02:00 INFO: [15026] Found 0 message changes in f317812c.
2013-06-15T22:49:38+02:00 INFO: [15026] Initializing state for  
collection: @Contacts@, synckey:  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:49:38+02:00 INFO: [15026] Loading state for synckey  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:49:38+02:00 INFO: [15026] Initializing message diff  
engine for @Contacts@ (@Contacts@)
2013-06-15T22:49:38+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 1371328790,  
1371329378, 0, 1)
2013-06-15T22:49:38+02:00 INFO: [15026] Found 0 message changes in @Contacts at .
2013-06-15T22:49:38+02:00 INFO: [15026] Sleeping for 5 seconds.
2013-06-15T22:49:42+02:00 INFO: [13650] SyncCache collections refreshed.
2013-06-15T22:49:42+02:00 INFO: [13650] Refreshing f317812c from the cache.
2013-06-15T22:49:42+02:00 INFO: [13650] Refreshing @Contacts@ from the cache.
2013-06-15T22:49:42+02:00 INFO: [13650] Changes in cache detected  
during PING, exiting here.
2013-06-15T22:49:42+02:00 INFO: [13650] User andreas logged off
2013-06-15T22:49:43+02:00 INFO: [15026] SyncCache collections refreshed.
2013-06-15T22:49:43+02:00 INFO: [15026] Refreshing f317812c from the cache.
2013-06-15T22:49:43+02:00 INFO: [15026] Refreshing @Contacts@ from the cache.
2013-06-15T22:49:43+02:00 INFO: [15026] Initializing state for  
collection: INBOX, synckey: {51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:49:43+02:00 INFO: [15026] Loading state for synckey  
{51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:49:43+02:00 INFO: [15026] Initializing message diff  
engine for f317812c (INBOX)
2013-06-15T22:49:43+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(INBOX, 1371328775,  
1371329383, 0, 1)
2013-06-15T22:49:43+02:00 INFO: [15026] Found 0 message changes in f317812c.
2013-06-15T22:49:43+02:00 INFO: [15026] Initializing state for  
collection: @Contacts@, synckey:  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:49:43+02:00 INFO: [15026] Loading state for synckey  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:49:43+02:00 INFO: [15026] Initializing message diff  
engine for @Contacts@ (@Contacts@)
2013-06-15T22:49:43+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 1371328790,  
1371329383, 0, 1)
2013-06-15T22:49:43+02:00 INFO: [15026] Found 0 message changes in @Contacts at .
2013-06-15T22:49:43+02:00 INFO: [15026] Sleeping for 5 seconds.
2013-06-15T22:49:48+02:00 INFO: [15026] SyncCache collections refreshed.
2013-06-15T22:49:48+02:00 INFO: [15026] Refreshing f317812c from the cache.
2013-06-15T22:49:48+02:00 INFO: [15026] Refreshing @Contacts@ from the cache.
2013-06-15T22:49:48+02:00 INFO: [15026] Initializing state for  
collection: INBOX, synckey: {51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:49:48+02:00 INFO: [15026] Loading state for synckey  
{51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:49:48+02:00 INFO: [15026] Initializing message diff  
engine for f317812c (INBOX)
2013-06-15T22:49:48+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(INBOX, 1371328775,  
1371329388, 0, 1)
2013-06-15T22:49:48+02:00 INFO: [15026] Found 0 message changes in f317812c.
2013-06-15T22:49:48+02:00 INFO: [15026] Initializing state for  
collection: @Contacts@, synckey:  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:49:48+02:00 INFO: [15026] Loading state for synckey  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:49:48+02:00 INFO: [15026] Initializing message diff  
engine for @Contacts@ (@Contacts@)
2013-06-15T22:49:48+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 1371328790,  
1371329388, 0, 1)
2013-06-15T22:49:48+02:00 INFO: [15026] Found 0 message changes in @Contacts at .
2013-06-15T22:49:48+02:00 INFO: [15026] Sleeping for 5 seconds.
2013-06-15T22:49:53+02:00 INFO: [15026] SyncCache collections refreshed.
2013-06-15T22:49:53+02:00 INFO: [15026] Refreshing f317812c from the cache.
2013-06-15T22:49:53+02:00 INFO: [15026] Refreshing @Contacts@ from the cache.
2013-06-15T22:49:53+02:00 INFO: [15026] Initializing state for  
collection: INBOX, synckey: {51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:49:53+02:00 INFO: [15026] Loading state for synckey  
{51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:49:53+02:00 INFO: [15026] Initializing message diff  
engine for f317812c (INBOX)
2013-06-15T22:49:53+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(INBOX, 1371328775,  
1371329393, 0, 1)
2013-06-15T22:49:53+02:00 INFO: [15026] Found 0 message changes in f317812c.
2013-06-15T22:49:53+02:00 INFO: [15026] Initializing state for  
collection: @Contacts@, synckey:  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:49:53+02:00 INFO: [15026] Loading state for synckey  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:49:53+02:00 INFO: [15026] Initializing message diff  
engine for @Contacts@ (@Contacts@)
2013-06-15T22:49:53+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 1371328790,  
1371329393, 0, 1)
2013-06-15T22:49:53+02:00 INFO: [15026] Found 0 message changes in @Contacts at .
2013-06-15T22:49:53+02:00 INFO: [15026] Sleeping for 5 seconds.
2013-06-15T22:49:58+02:00 INFO: [15026] SyncCache collections refreshed.
2013-06-15T22:49:58+02:00 INFO: [15026] Refreshing f317812c from the cache.
2013-06-15T22:49:58+02:00 INFO: [15026] Refreshing @Contacts@ from the cache.
2013-06-15T22:49:58+02:00 INFO: [15026] Initializing state for  
collection: INBOX, synckey: {51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:49:58+02:00 INFO: [15026] Loading state for synckey  
{51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:49:58+02:00 INFO: [15026] Initializing message diff  
engine for f317812c (INBOX)
2013-06-15T22:49:58+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(INBOX, 1371328775,  
1371329398, 0, 1)
2013-06-15T22:49:58+02:00 INFO: [15026] Found 0 message changes in f317812c.
2013-06-15T22:49:58+02:00 INFO: [15026] Initializing state for  
collection: @Contacts@, synckey:  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:49:58+02:00 INFO: [15026] Loading state for synckey  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:49:58+02:00 INFO: [15026] Initializing message diff  
engine for @Contacts@ (@Contacts@)
2013-06-15T22:49:58+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 1371328790,  
1371329398, 0, 1)
2013-06-15T22:49:58+02:00 INFO: [15026] Found 0 message changes in @Contacts at .
2013-06-15T22:49:58+02:00 INFO: [15026] Sleeping for 5 seconds.
2013-06-15T22:50:03+02:00 INFO: [15026] SyncCache collections refreshed.
2013-06-15T22:50:03+02:00 INFO: [15026] Refreshing f317812c from the cache.
2013-06-15T22:50:03+02:00 INFO: [15026] Refreshing @Contacts@ from the cache.
2013-06-15T22:50:03+02:00 INFO: [15026] Initializing state for  
collection: INBOX, synckey: {51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:50:03+02:00 INFO: [15026] Loading state for synckey  
{51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:50:03+02:00 INFO: [15026] Initializing message diff  
engine for f317812c (INBOX)
2013-06-15T22:50:03+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(INBOX, 1371328775,  
1371329403, 0, 1)
2013-06-15T22:50:03+02:00 INFO: [15026] Found 0 message changes in f317812c.
2013-06-15T22:50:03+02:00 INFO: [15026] Initializing state for  
collection: @Contacts@, synckey:  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:50:03+02:00 INFO: [15026] Loading state for synckey  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:50:03+02:00 INFO: [15026] Initializing message diff  
engine for @Contacts@ (@Contacts@)
2013-06-15T22:50:03+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 1371328790,  
1371329403, 0, 1)
2013-06-15T22:50:03+02:00 INFO: [15026] Found 0 message changes in @Contacts at .
2013-06-15T22:50:03+02:00 INFO: [15026] Sleeping for 5 seconds.
2013-06-15T22:50:08+02:00 INFO: [15026] SyncCache collections refreshed.
2013-06-15T22:50:08+02:00 INFO: [15026] Refreshing f317812c from the cache.
2013-06-15T22:50:08+02:00 INFO: [15026] Refreshing @Contacts@ from the cache.
2013-06-15T22:50:08+02:00 INFO: [15026] Initializing state for  
collection: INBOX, synckey: {51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:50:08+02:00 INFO: [15026] Loading state for synckey  
{51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:50:08+02:00 INFO: [15026] Initializing message diff  
engine for f317812c (INBOX)
2013-06-15T22:50:08+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(INBOX, 1371328775,  
1371329408, 0, 1)
2013-06-15T22:50:08+02:00 INFO: [15026] Found 0 message changes in f317812c.
2013-06-15T22:50:08+02:00 INFO: [15026] Initializing state for  
collection: @Contacts@, synckey:  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:50:08+02:00 INFO: [15026] Loading state for synckey  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:50:08+02:00 INFO: [15026] Initializing message diff  
engine for @Contacts@ (@Contacts@)
2013-06-15T22:50:08+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 1371328790,  
1371329408, 0, 1)
2013-06-15T22:50:08+02:00 INFO: [15026] Found 0 message changes in @Contacts at .
2013-06-15T22:50:08+02:00 INFO: [15026] Sleeping for 5 seconds.
2013-06-15T22:50:13+02:00 INFO: [15026] SyncCache collections refreshed.
2013-06-15T22:50:13+02:00 INFO: [15026] Refreshing f317812c from the cache.
2013-06-15T22:50:13+02:00 INFO: [15026] Refreshing @Contacts@ from the cache.
2013-06-15T22:50:13+02:00 INFO: [15026] Initializing state for  
collection: INBOX, synckey: {51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:50:13+02:00 INFO: [15026] Loading state for synckey  
{51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:50:13+02:00 INFO: [15026] Initializing message diff  
engine for f317812c (INBOX)
2013-06-15T22:50:13+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(INBOX, 1371328775,  
1371329413, 0, 1)
2013-06-15T22:50:13+02:00 INFO: [15026] Found 0 message changes in f317812c.
2013-06-15T22:50:13+02:00 INFO: [15026] Initializing state for  
collection: @Contacts@, synckey:  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:50:13+02:00 INFO: [15026] Loading state for synckey  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:50:13+02:00 INFO: [15026] Initializing message diff  
engine for @Contacts@ (@Contacts@)
2013-06-15T22:50:13+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 1371328790,  
1371329413, 0, 1)
2013-06-15T22:50:13+02:00 INFO: [15026] Found 0 message changes in @Contacts at .
2013-06-15T22:50:13+02:00 INFO: [15026] Sleeping for 5 seconds.
2013-06-15T22:50:18+02:00 INFO: [15026] SyncCache collections refreshed.
2013-06-15T22:50:18+02:00 INFO: [15026] Refreshing f317812c from the cache.
2013-06-15T22:50:18+02:00 INFO: [15026] Refreshing @Contacts@ from the cache.
2013-06-15T22:50:18+02:00 INFO: [15026] Initializing state for  
collection: INBOX, synckey: {51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:50:18+02:00 INFO: [15026] Loading state for synckey  
{51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:50:18+02:00 INFO: [15026] Initializing message diff  
engine for f317812c (INBOX)
2013-06-15T22:50:18+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(INBOX, 1371328775,  
1371329418, 0, 1)
2013-06-15T22:50:18+02:00 INFO: [15026] Found 0 message changes in f317812c.
2013-06-15T22:50:18+02:00 INFO: [15026] Initializing state for  
collection: @Contacts@, synckey:  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:50:18+02:00 INFO: [15026] Loading state for synckey  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:50:18+02:00 INFO: [15026] Initializing message diff  
engine for @Contacts@ (@Contacts@)
2013-06-15T22:50:18+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 1371328790,  
1371329418, 0, 1)
2013-06-15T22:50:18+02:00 INFO: [15026] Found 0 message changes in @Contacts at .
2013-06-15T22:50:18+02:00 INFO: [15026] Sleeping for 5 seconds.
2013-06-15T22:50:23+02:00 INFO: [15026] SyncCache collections refreshed.
2013-06-15T22:50:23+02:00 INFO: [15026] Refreshing f317812c from the cache.
2013-06-15T22:50:23+02:00 INFO: [15026] Refreshing @Contacts@ from the cache.
2013-06-15T22:50:23+02:00 INFO: [15026] Initializing state for  
collection: INBOX, synckey: {51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:50:23+02:00 INFO: [15026] Loading state for synckey  
{51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:50:23+02:00 INFO: [15026] Initializing message diff  
engine for f317812c (INBOX)
2013-06-15T22:50:23+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(INBOX, 1371328775,  
1371329423, 0, 1)
2013-06-15T22:50:23+02:00 INFO: [15026] Found 0 message changes in f317812c.
2013-06-15T22:50:23+02:00 INFO: [15026] Initializing state for  
collection: @Contacts@, synckey:  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:50:23+02:00 INFO: [15026] Loading state for synckey  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:50:23+02:00 INFO: [15026] Initializing message diff  
engine for @Contacts@ (@Contacts@)
2013-06-15T22:50:23+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 1371328790,  
1371329423, 0, 1)
2013-06-15T22:50:23+02:00 INFO: [15026] Found 0 message changes in @Contacts at .
2013-06-15T22:50:23+02:00 INFO: [15026] Sleeping for 5 seconds.
2013-06-15T22:50:28+02:00 INFO: [15026] SyncCache collections refreshed.
2013-06-15T22:50:28+02:00 INFO: [15026] Refreshing f317812c from the cache.
2013-06-15T22:50:28+02:00 INFO: [15026] Refreshing @Contacts@ from the cache.
2013-06-15T22:50:28+02:00 INFO: [15026] Initializing state for  
collection: INBOX, synckey: {51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:50:28+02:00 INFO: [15026] Loading state for synckey  
{51bcd09e-d564-43fe-a1dc-2d7c0a001802}52
2013-06-15T22:50:28+02:00 INFO: [15026] Initializing message diff  
engine for f317812c (INBOX)
2013-06-15T22:50:28+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(INBOX, 1371328775,  
1371329428, 0, 1)
2013-06-15T22:50:28+02:00 INFO: [15026] Found 0 message changes in f317812c.
2013-06-15T22:50:28+02:00 INFO: [15026] Initializing state for  
collection: @Contacts@, synckey:  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:50:28+02:00 INFO: [15026] Loading state for synckey  
{51bcd09f-0698-454f-8080-2d440a001802}58
2013-06-15T22:50:28+02:00 INFO: [15026] Initializing message diff  
engine for @Contacts@ (@Contacts@)
2013-06-15T22:50:28+02:00 INFO: [15026]  
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 1371328790,  
1371329428, 0, 1)
2013-06-15T22:50:28+02:00 INFO: [15026] Found 0 message changes in @Contacts at .
2013-06-15T22:50:28+02:00 INFO: [15026] Sleeping for 5 seconds.

And thats why I get the sql error?

2013-06-15T22:44:47.166600+02:00 webster HORDE: [horde] SQL QUERY  
FAILED: SQLSTATE[23000]: Integrity constraint violation: 1062  
Duplicate entry '{51bcd09f-5710-45ef-861b-2d560a001802}7' 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   
('{51bcd09f-5710-45ef-861b-2d560a001802}7',#012#011   
'O:34:"Horde_ActiveSync_Folder_Collection":3:{s:10:"
Jun 15 22:44:47 webster HORDE: [horde] SQL QUERY FAILED:  
SQLSTATE[23000]: Integrity constraint violation: 1062 Duplicate entry  
'{51bcd09f-5710-45ef-861b-2d560a001802}7' 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   
('{51bcd09f-5710-45ef-861b-2d560a001802}7',#012#011   
'O:34:"Horde_ActiveSync_Folder_Collection":3:{s:10:"

Thank you for helping,
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/889cb378/attachment-0001.bin>


More information about the horde mailing list