[Tickets #11349] Duplicate synckeys

bugs at horde.org bugs at horde.org
Mon Aug 13 09:49:14 UTC 2012 

DO NOT REPLY TO THIS MESSAGE. THIS EMAIL ADDRESS IS NOT MONITORED.

Ticket URL: http://bugs.horde.org/ticket/11349
------------------------------------------------------------------------------
  Ticket             | 11349
  Created By         | Jan Schneider <jan at horde.org>
  Summary            | Duplicate synckeys
  Queue              | Synchronization
  Version            | Git develop
  Type               | Bug
  State              | Assigned
  Priority           | 1. Low
  Milestone          |
  Patch              |
  Owners             | Michael Rubinsky
------------------------------------------------------------------------------


Jan Schneider <jan at horde.org> (2012-08-13 11:49) wrote:

With a fresh AS sync, I currently get this:

2012-08-13T11:45:37+02:00 DEBUG: [androidc135094322] SYNC request  
received for user jan
2012-08-13T11:45:37+02:00 DEBUG: [androidc135094322] loadDeviceInfo: jan
2012-08-13T11:45:37+02:00 INFO: Request being handled for device:  
androidc135094322 Supporting protocol version: 12.0
2012-08-13T11:45:37+02:00 INFO: [androidc135094322] Handling SYNC command.
2012-08-13T11:45:37+02:00 DEBUG: [androidc135094322] Checking  
policykey for device: 0 user: jan
2012-08-13T11:45:37+02:00 DEBUG: Policykey: 0 verified.
2012-08-13T11:45:37+02:00 DEBUG: I  <Synchronize>
2012-08-13T11:45:37+02:00 DEBUG: I   <Folders>
2012-08-13T11:45:37+02:00 DEBUG: I    <Folder>
2012-08-13T11:45:37+02:00 DEBUG: I     <FolderType>
2012-08-13T11:45:37+02:00 DEBUG: I      Email
2012-08-13T11:45:37+02:00 INFO: [androidc135094322] Syncing folder  
class: Email
2012-08-13T11:45:37+02:00 DEBUG: I     </FolderType>
2012-08-13T11:45:37+02:00 DEBUG: I     <SyncKey>
2012-08-13T11:45:37+02:00 DEBUG: I       
{5028c803-917c-47ae-b757-760cc0a83c01}1
2012-08-13T11:45:37+02:00 DEBUG: I     </SyncKey>
2012-08-13T11:45:37+02:00 DEBUG: I     <FolderId>
2012-08-13T11:45:37+02:00 DEBUG: I      INBOX
2012-08-13T11:45:37+02:00 INFO: [androidc135094322] Folder server id: INBOX
2012-08-13T11:45:37+02:00 DEBUG: I     </FolderId>
2012-08-13T11:45:37+02:00 DEBUG: I     <DeletesAsMoves/>
2012-08-13T11:45:37+02:00 DEBUG: I     <GetChanges/>
2012-08-13T11:45:37+02:00 ERR: Unmatched content:
2012-08-13T11:45:37+02:00 ERR: Array
(
     [1] => 1
     [2] => GetChanges
     [4] => 0
)

2012-08-13T11:45:37+02:00 DEBUG: I     <WindowSize>
2012-08-13T11:45:37+02:00 ERR: Unmatched content:
2012-08-13T11:45:37+02:00 ERR: Array
(
     [1] => 1
     [2] => WindowSize
     [4] => 1
)

2012-08-13T11:45:37+02:00 DEBUG: I      5
2012-08-13T11:45:37+02:00 DEBUG: I     </WindowSize>
2012-08-13T11:45:37+02:00 DEBUG: I     <Options>
2012-08-13T11:45:37+02:00 DEBUG: I      <FilterType>
2012-08-13T11:45:37+02:00 DEBUG: I       2
2012-08-13T11:45:37+02:00 DEBUG: I      </FilterType>
2012-08-13T11:45:37+02:00 DEBUG: I      <AirSyncBase:BodyPreference>
2012-08-13T11:45:37+02:00 DEBUG: I       <AirSyncBase:Type>
2012-08-13T11:45:37+02:00 DEBUG: I        2
2012-08-13T11:45:37+02:00 DEBUG: I       </AirSyncBase:Type>
2012-08-13T11:45:37+02:00 DEBUG: I       <AirSyncBase:TruncationSize>
2012-08-13T11:45:37+02:00 DEBUG: I        200000
2012-08-13T11:45:37+02:00 DEBUG: I       </AirSyncBase:TruncationSize>
2012-08-13T11:45:37+02:00 DEBUG: I      </AirSyncBase:BodyPreference>
2012-08-13T11:45:37+02:00 DEBUG: I     </Options>
2012-08-13T11:45:37+02:00 DEBUG: I    </Folder>
2012-08-13T11:45:37+02:00 DEBUG: I   </Folders>
2012-08-13T11:45:37+02:00 DEBUG: I  </Synchronize>
2012-08-13T11:45:37+02:00 DEBUG: Have syncable collections
2012-08-13T11:45:37+02:00 DEBUG: All synckeys confirmed. Continuing with SYNC
2012-08-13T11:45:37+02:00 DEBUG: [30286] Initializing state for  
collection: INBOX, synckey: {5028c803-917c-47ae-b757-760cc0a83c01}1
2012-08-13T11:45:37+02:00 DEBUG: [30286] Loading state for synckey  
{5028c803-917c-47ae-b757-760cc0a83c01}1
2012-08-13T11:45:37+02:00 DEBUG: [30286] Initializing message diff  
engine for INBOX
2012-08-13T11:45:37+02:00 DEBUG:  
Horde_ActiveSync_Driver_Horde::getServerChanges(INBOX, 0, 1344851137,  
1344591937, 0)
2012-08-13T11:45:37+02:00 DEBUG: [30286] Found 0 message changes.
2012-08-13T11:45:37+02:00 DEBUG: Old SYNCKEY:  
{5028c803-917c-47ae-b757-760cc0a83c01}1, New SYNCKEY:  
{5028c803-917c-47ae-b757-760cc0a83c01}2
2012-08-13T11:45:37+02:00 DEBUG: O  <Synchronize>
2012-08-13T11:45:37+02:00 DEBUG: O   <Folders>
2012-08-13T11:45:37+02:00 DEBUG: O    <Folder>
2012-08-13T11:45:37+02:00 DEBUG: O     <FolderType>
2012-08-13T11:45:37+02:00 DEBUG: O      Email
2012-08-13T11:45:37+02:00 DEBUG: O     <FolderType/>
2012-08-13T11:45:37+02:00 DEBUG: O     <SyncKey>
2012-08-13T11:45:37+02:00 DEBUG: O       
{5028c803-917c-47ae-b757-760cc0a83c01}2
2012-08-13T11:45:37+02:00 DEBUG: O     <SyncKey/>
2012-08-13T11:45:37+02:00 DEBUG: O     <FolderId>
2012-08-13T11:45:37+02:00 DEBUG: O      INBOX
2012-08-13T11:45:37+02:00 DEBUG: O     <FolderId/>
2012-08-13T11:45:37+02:00 DEBUG: O     <Status>
2012-08-13T11:45:37+02:00 DEBUG: O      1
2012-08-13T11:45:37+02:00 DEBUG: O     <Status/>
2012-08-13T11:45:37+02:00 DEBUG: [30286] Saving state: Array
(
     [0] => {5028c803-917c-47ae-b757-760cc0a83c01}2
     [1] =>  
O:28:"Horde_ActiveSync_Folder_Imap":9:{s:12:"*_messages";a:0:{}s:9:"*_added";a:0:{}s:11:"*_changed";a:0:{}s:11:"*_removed";a:0:{}s:9:"*_flags";a:0:{}s:7:"*_min";i:0;s:10:"*_status";a:0:{}s:12:"*_serverid";s:5:"INBOX";s:9:"*_class";s:5:"Email";}
     [2] => androidc135094322
     [3] => 1344851137
     [4] => INBOX
     [5] => jan
     [6] => 0
)

2012-08-13T11:45:37+02:00 NOTICE: [30286] Error saving state for  
synckey {5028c803-917c-47ae-b757-760cc0a83c01}2: SQLSTATE[23000]:  
Integrity constraint violation: 1062 Duplicate entry  
'{5028c803-917c-47ae-b757-760cc0a83c01}2' for key 'PRIMARY' - removing  
previous sync state and trying again.
2012-08-13T11:45:37+02:00 DEBUG: O    <Folder/>
2012-08-13T11:45:37+02:00 DEBUG: O   <Folders/>
2012-08-13T11:45:37+02:00 DEBUG: O  <Synchronize/>

More information about the bugs mailing list