[horde] little ActiveSync problem with mail seen-flag

Thomas Bühlmann tomnet at tbuhl.ch
Mon Nov 26 09:55:52 UTC 2012


Hello List!

First, thank you all for such a great product!

 On my horde install (all latest pear) i use dovecot2 to manage mail.
Everything (calendar, contacts, email) works fine, except the seen-flag
doesn't get synced to the device.
the other way around works fine. Messages marked "seen" on the device
get synced
the next time.( i know they're not synched immediately!)

Please see attached horde-logs where i tried several things.
(i always synched manually after doeing the action on the test-e-mail).
I'm not eve sure if my device (sony experia active, latest ICS stock
rom) does the error or if
it's on the horde side.
I still try to get a "emaillog.txt" out of the xperia, but until now
without success.
Perhaps one of the more savy people in activesync can help.

Thanks in advance
--Tom



-------------- next part --------------
2012-11-26T08:15:48+00:00 INFO: Horde_Core_ActiveSync_Driver::authenticate() attempt for: XXXXX
2012-11-26T08:15:48+00:00 INFO: Horde_Core_ActiveSync_Driver::authenticate() attempt for: XXXXX
2012-11-26T08:15:48+00:00 DEBUG: [androidc1500666295] SYNC request received for user XXXXX
2012-11-26T08:15:48+00:00 DEBUG: [androidc1500666295] loadDeviceInfo: XXXXX
2012-11-26T08:15:48+00:00 INFO: Request being handled for device: androidc1500666295 Supporting protocol version: 2.5
2012-11-26T08:15:48+00:00 INFO: [androidc1500666295] Handling SYNC command.
2012-11-26T08:15:48+00:00 DEBUG: [androidc1500666295] Checking policykey for device: 7465172901 user: XXXXX
2012-11-26T08:15:48+00:00 DEBUG: [androidc1500666295] Stored key: 7465172901
2012-11-26T08:15:48+00:00 DEBUG: Policykey: 7465172901 verified.
2012-11-26T08:15:48+00:00 DEBUG: I  <Synchronize>
2012-11-26T08:15:48+00:00 DEBUG: I   <Folders>
2012-11-26T08:15:48+00:00 DEBUG: I    <Folder>
2012-11-26T08:15:48+00:00 DEBUG: I     <FolderType>
2012-11-26T08:15:48+00:00 DEBUG: I      Email
2012-11-26T08:15:48+00:00 INFO: [androidc1500666295] Syncing folder class: Email
2012-11-26T08:15:48+00:00 DEBUG: I     </FolderType>
2012-11-26T08:15:48+00:00 DEBUG: I     <SyncKey>
2012-11-26T08:15:48+00:00 DEBUG: I      {50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}71
2012-11-26T08:15:48+00:00 DEBUG: I     </SyncKey>
2012-11-26T08:15:48+00:00 DEBUG: I     <FolderId>
2012-11-26T08:15:48+00:00 DEBUG: I      INBOX
2012-11-26T08:15:48+00:00 INFO: [androidc1500666295] Folder server id: INBOX
2012-11-26T08:15:48+00:00 DEBUG: I     </FolderId>
2012-11-26T08:15:48+00:00 DEBUG: I     <DeletesAsMoves/>
2012-11-26T08:15:48+00:00 DEBUG: I     <GetChanges/>
2012-11-26T08:15:48+00:00 ERR: Unmatched content:
2012-11-26T08:15:48+00:00 ERR: Array
(
    [1] => 1
    [2] => GetChanges
    [4] => 0
)

2012-11-26T08:15:48+00:00 DEBUG: I     <WindowSize>
2012-11-26T08:15:48+00:00 ERR: Unmatched content:
2012-11-26T08:15:48+00:00 ERR: Array
(
    [1] => 1
    [2] => WindowSize
    [4] => 1
)

2012-11-26T08:15:48+00:00 DEBUG: I      5
2012-11-26T08:15:48+00:00 DEBUG: I     </WindowSize>
2012-11-26T08:15:48+00:00 DEBUG: I     <Options>
2012-11-26T08:15:48+00:00 DEBUG: I      <FilterType>
2012-11-26T08:15:48+00:00 DEBUG: I       0
2012-11-26T08:15:48+00:00 DEBUG: I      </FilterType>
2012-11-26T08:15:48+00:00 DEBUG: I      <MIMESupport>
2012-11-26T08:15:48+00:00 DEBUG: I       2
2012-11-26T08:15:48+00:00 DEBUG: I      </MIMESupport>
2012-11-26T08:15:48+00:00 DEBUG: I      <MIMETruncation>
2012-11-26T08:15:48+00:00 DEBUG: I       7
2012-11-26T08:15:48+00:00 DEBUG: I      </MIMETruncation>
2012-11-26T08:15:48+00:00 DEBUG: I     </Options>
2012-11-26T08:15:48+00:00 DEBUG: I     <Commands>
2012-11-26T08:15:48+00:00 DEBUG: [20583] Initializing state for collection: INBOX, synckey: {50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}71
2012-11-26T08:15:48+00:00 DEBUG: [20583] Loading state for synckey {50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}71
2012-11-26T08:15:49+00:00 DEBUG: [androidc1500666295] PING request received for user XXXXX
2012-11-26T08:15:49+00:00 DEBUG: [androidc1500666295] loadDeviceInfo: XXXXX
2012-11-26T08:15:49+00:00 INFO: Request being handled for device: androidc1500666295 Supporting protocol version: 2.5
2012-11-26T08:15:49+00:00 INFO: [19936] PING received at timestamp: 1353917749.
2012-11-26T08:15:49+00:00 DEBUG: I  <Ping:Ping>
2012-11-26T08:15:49+00:00 DEBUG: I   <Ping:HeartbeatInterval>
2012-11-26T08:15:49+00:00 DEBUG: I    470
2012-11-26T08:15:49+00:00 DEBUG: I   </Ping:HeartbeatInterval>
2012-11-26T08:15:49+00:00 DEBUG: I   <Ping:Folders>
2012-11-26T08:15:49+00:00 DEBUG: I    <Ping:Folder>
2012-11-26T08:15:49+00:00 DEBUG: I     <Ping:ServerEntryId>
2012-11-26T08:15:49+00:00 DEBUG: I      @Contacts@
2012-11-26T08:15:49+00:00 DEBUG: I     </Ping:ServerEntryId>
2012-11-26T08:15:49+00:00 DEBUG: I     <Ping:FolderType>
2012-11-26T08:15:49+00:00 DEBUG: I      Contacts
2012-11-26T08:15:49+00:00 DEBUG: I     </Ping:FolderType>
2012-11-26T08:15:49+00:00 DEBUG: I    </Ping:Folder>
2012-11-26T08:15:49+00:00 DEBUG: I    <Ping:Folder>
2012-11-26T08:15:49+00:00 DEBUG: I     <Ping:ServerEntryId>
2012-11-26T08:15:49+00:00 DEBUG: I      @Calendar@
2012-11-26T08:15:49+00:00 DEBUG: I     </Ping:ServerEntryId>
2012-11-26T08:15:49+00:00 DEBUG: I     <Ping:FolderType>
2012-11-26T08:15:49+00:00 DEBUG: I      Calendar
2012-11-26T08:15:49+00:00 DEBUG: I     </Ping:FolderType>
2012-11-26T08:15:49+00:00 DEBUG: I    </Ping:Folder>
2012-11-26T08:15:49+00:00 DEBUG: I   </Ping:Folders>
2012-11-26T08:15:49+00:00 DEBUG: I  </Ping:Ping>
2012-11-26T08:15:49+00:00 INFO: [19936] Waiting for changes (heartbeat interval: 470)
2012-11-26T08:15:49+00:00 DEBUG: [19936] Initializing state for collection: @Contacts@, synckey: {50af8a31-ef4c-44b7-b92d-45b2c0a8000a}28
2012-11-26T08:15:49+00:00 DEBUG: [19936] Loading state for synckey {50af8a31-ef4c-44b7-b92d-45b2c0a8000a}28
2012-11-26T08:15:49+00:00 DEBUG: [19936] Initializing message diff engine for @Contacts@
2012-11-26T08:15:49+00:00 DEBUG: Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 1353681572, 1353917749, 0, 1)
2012-11-26T08:15:49+00:00 DEBUG: [19936] Found 0 message changes in @Contacts at .
2012-11-26T08:15:49+00:00 DEBUG: [19936] Initializing state for collection: @Calendar@, synckey: {50b2d6c3-224c-4fb0-b6a5-2ef4c0a8000a}11
2012-11-26T08:15:49+00:00 DEBUG: [19936] Loading state for synckey {50b2d6c3-224c-4fb0-b6a5-2ef4c0a8000a}11
2012-11-26T08:15:49+00:00 DEBUG: [19936] Initializing message diff engine for @Calendar@
2012-11-26T08:15:49+00:00 DEBUG: Horde_Core_ActiveSync_Driver::getServerChanges(@Calendar@, 1353897682, 1353917749, 0, 1)
2012-11-26T08:15:49+00:00 DEBUG: I      <Modify>
2012-11-26T08:15:49+00:00 DEBUG: I       <ServerEntryId>
2012-11-26T08:15:49+00:00 DEBUG: I        38217
2012-11-26T08:15:49+00:00 DEBUG: I       </ServerEntryId>
2012-11-26T08:15:49+00:00 DEBUG: I       <Data>
2012-11-26T08:15:49+00:00 DEBUG: I        <POOMMAIL:Read>
2012-11-26T08:15:49+00:00 DEBUG: I         0
2012-11-26T08:15:49+00:00 DEBUG: I        </POOMMAIL:Read>
2012-11-26T08:15:49+00:00 DEBUG: I       </Data>
2012-11-26T08:15:49+00:00 DEBUG: Updating state during flags
2012-11-26T09:15:49+01:00 DEBUG: [19936] Found 0 message changes in @Calendar at .
2012-11-26T08:15:50+00:00 DEBUG: I      </Modify>
2012-11-26T08:15:50+00:00 DEBUG: I      <Modify>
2012-11-26T08:15:50+00:00 DEBUG: I       <ServerEntryId>
2012-11-26T08:15:50+00:00 DEBUG: I        38216
2012-11-26T08:15:50+00:00 DEBUG: I       </ServerEntryId>
2012-11-26T08:15:50+00:00 DEBUG: I       <Data>
2012-11-26T08:15:50+00:00 DEBUG: I        <POOMMAIL:Read>
2012-11-26T08:15:50+00:00 DEBUG: I         1
2012-11-26T08:15:50+00:00 DEBUG: I        </POOMMAIL:Read>
2012-11-26T08:15:50+00:00 DEBUG: I       </Data>
2012-11-26T08:15:50+00:00 DEBUG: Updating state during flags
2012-11-26T08:15:50+00:00 DEBUG: I      </Modify>
2012-11-26T08:15:50+00:00 DEBUG: I     </Commands>
2012-11-26T08:15:50+00:00 DEBUG: [androidc1500666295] Processed 2 incoming changes
2012-11-26T08:15:50+00:00 DEBUG: I    </Folder>
2012-11-26T08:15:50+00:00 DEBUG: I   </Folders>
2012-11-26T08:15:50+00:00 DEBUG: I  </Synchronize>
2012-11-26T08:15:50+00:00 DEBUG: Have syncable collections
2012-11-26T08:15:50+00:00 DEBUG: All synckeys confirmed. Continuing with SYNC
2012-11-26T08:15:50+00:00 DEBUG: Replacing SYNC_CACHE entry for user XXXXX and device androidc1500666295: a:12:{s:18:"confirmed_synckeys";a:0:{}s:17:"lasthbsyncstarted";i:1353917351;s:17:"lastsyncendnormal";i:1353917431;s:9:"lastuntil";i:1353917750;s:9:"timestamp";s:10:"1353917750";s:4:"wait";b:0;s:10:"hbinterval";b:0;s:7:"folders";a:0:{}s:9:"hierarchy";b:0;s:11:"collections";a:5:{s:10:"@Contacts@";a:13:{s:5:"class";s:8:"Contacts";s:10:"windowsize";s:1:"4";s:14:"deletesasmoves";b:1;s:10:"filtertype";N;s:10:"truncation";s:1:"7";s:13:"rtftruncation";N;s:11:"mimesupport";i:0;s:14:"mimetruncation";N;s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50af8a31-ef4c-44b7-b92d-45b2c0a8000a}28";s:2:"id";s:10:"@Contacts@";s:8:"pingable";b:1;}s:5:"horde";a:12:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:13:"rtftruncation";N;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:39:"{50b22f5e-b4fc-4ac1-98df-7875c0a8000a}4";s:2:"id";s:5:"horde";}s:8:"opensuse";a:12:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:13:"rtftruncation";N;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50b22f8b-f134-4b75-98fc-198fc0a8000a}23";s:2:"id";s:8:"opensuse";}s:10:"@Calendar@";a:12:{s:5:"class";s:8:"Calendar";s:10:"windowsize";s:1:"4";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"4";s:10:"truncation";s:1:"7";s:13:"rtftruncation";N;s:11:"mimesupport";i:0;s:14:"mimetruncation";N;s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50b2d6c3-224c-4fb0-b6a5-2ef4c0a8000a}11";s:2:"id";s:10:"@Calendar@";}s:5:"INBOX";a:13:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:13:"rtftruncation";N;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}71";s:2:"id";s:5:"INBOX";s:7:"synckey";s:40:"{50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}71";}}s:14:"synckeycounter";a:5:{s:10:"@Contacts@";a:1:{s:40:"{50af8a31-ef4c-44b7-b92d-45b2c0a8000a}28";i:71;}s:5:"horde";a:1:{s:39:"{50b22f5e-b4fc-4ac1-98df-7875c0a8000a}3";i:1;}s:8:"opensuse";a:1:{s:40:"{50b22f8b-f134-4b75-98fc-198fc0a8000a}21";i:1;}s:10:"@Calendar@";a:1:{s:40:"{50b2d6c3-224c-4fb0-b6a5-2ef4c0a8000a}11";i:19;}s:5:"INBOX";a:1:{s:40:"{50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}71";i:1;}}s:13:"pingheartbeat";s:3:"470";}
2012-11-26T08:15:51+00:00 DEBUG: O  <Synchronize>
2012-11-26T08:15:51+00:00 DEBUG: O   <Status>
2012-11-26T08:15:51+00:00 DEBUG: O    1
2012-11-26T08:15:51+00:00 DEBUG: O   <Status/>
2012-11-26T08:15:51+00:00 DEBUG: [20583] Initializing state for collection: INBOX, synckey: {50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}71
2012-11-26T08:15:51+00:00 DEBUG: [20583] Loading state for synckey {50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}71
2012-11-26T08:15:51+00:00 DEBUG: [20583] Initializing message diff engine for INBOX
2012-11-26T08:15:51+00:00 DEBUG: Horde_Core_ActiveSync_Driver::getServerChanges(INBOX, 1353917714, 1353917751, 0, 0)
2012-11-26T08:15:51+00:00 DEBUG: IMAP status: Array
(
    [uidnext] => 38218
    [uidvalidity] => 1307656867
    [highestmodseq] => 1645
)

2012-11-26T08:15:51+00:00 DEBUG: CONDSTORE and CHANGES
2012-11-26T08:15:51+00:00 DEBUG: [20583] Found 2 message changes in INBOX.
2012-11-26T08:15:51+00:00 DEBUG: [20583] Checking for PIM initiated changes.
2012-11-26T08:15:51+00:00 DEBUG: _isPIMChange: 38216, Array
(
    [read] => 1
)
, flags
2012-11-26T08:15:51+00:00 DEBUG: [20583] Ignoring PIM initiated flag change for 38216
2012-11-26T08:15:51+00:00 DEBUG: _isPIMChange: 38217, Array
(
    [read] => 0
)
, flags
2012-11-26T08:15:51+00:00 DEBUG: [20583] Ignoring PIM initiated flag change for 38217
2012-11-26T08:15:51+00:00 DEBUG: Old SYNCKEY: {50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}71, New SYNCKEY: {50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72
2012-11-26T08:15:51+00:00 DEBUG: O   <Folders>
2012-11-26T08:15:51+00:00 DEBUG: O    <Folder>
2012-11-26T08:15:51+00:00 DEBUG: O     <FolderType>
2012-11-26T08:15:51+00:00 DEBUG: O      Email
2012-11-26T08:15:51+00:00 DEBUG: O     <FolderType/>
2012-11-26T08:15:51+00:00 DEBUG: O     <SyncKey>
2012-11-26T08:15:51+00:00 DEBUG: O      {50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72
2012-11-26T08:15:51+00:00 DEBUG: O     <SyncKey/>
2012-11-26T08:15:51+00:00 DEBUG: O     <FolderId>
2012-11-26T08:15:51+00:00 DEBUG: O      INBOX
2012-11-26T08:15:51+00:00 DEBUG: O     <FolderId/>
2012-11-26T08:15:51+00:00 DEBUG: O     <Status>
2012-11-26T08:15:51+00:00 DEBUG: O      1
2012-11-26T08:15:51+00:00 DEBUG: O     <Status/>
2012-11-26T08:15:51+00:00 DEBUG: [20583] Saving state: Array
(
    [0] => {50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72
    [1] => C:28:"Horde_ActiveSync_Folder_Imap":437:{a:5:{s:1:"s";a:3:{s:7:"uidnext";s:5:"38218";s:11:"uidvalidity";s:10:"1307656867";s:13:"highestmodseq";s:4:"1645";}s:1:"m";a:20:{i:0;i:38179;i:1;i:38181;i:2;i:38185;i:3;i:38186;i:9;i:38192;i:10;i:38197;i:15;i:38202;i:16;i:38204;i:17;i:38206;i:18;i:38207;i:19;i:38208;i:20;i:38209;i:21;i:38210;i:22;i:38211;i:23;i:38212;i:24;i:38213;i:25;i:38214;i:26;i:38215;i:27;i:38216;i:28;i:38217;}s:1:"f";s:5:"INBOX";s:1:"c";s:5:"Email";s:1:"v";i:1;}}
    [2] => androidc1500666295
    [3] => 1353917751
    [4] => INBOX
    [5] => XXXXX
    [6] => 0
)

2012-11-26T08:15:51+00:00 DEBUG: O    <Folder/>
2012-11-26T08:15:51+00:00 DEBUG: O   <Folders/>
2012-11-26T08:15:51+00:00 DEBUG: O  <Synchronize/>
2012-11-26T08:15:51+00:00 DEBUG: Replacing SYNC_CACHE entry for user XXXXX and device androidc1500666295: a:12:{s:18:"confirmed_synckeys";a:1:{s:40:"{50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72";b:1;}s:17:"lasthbsyncstarted";i:1353917351;s:17:"lastsyncendnormal";i:1353917431;s:9:"lastuntil";i:1353917750;s:9:"timestamp";s:10:"1353917751";s:4:"wait";b:0;s:10:"hbinterval";b:0;s:7:"folders";a:0:{}s:9:"hierarchy";b:0;s:11:"collections";a:5:{s:10:"@Contacts@";a:13:{s:5:"class";s:8:"Contacts";s:10:"windowsize";s:1:"4";s:14:"deletesasmoves";b:1;s:10:"filtertype";N;s:10:"truncation";s:1:"7";s:13:"rtftruncation";N;s:11:"mimesupport";i:0;s:14:"mimetruncation";N;s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50af8a31-ef4c-44b7-b92d-45b2c0a8000a}28";s:2:"id";s:10:"@Contacts@";s:8:"pingable";b:1;}s:5:"horde";a:12:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:13:"rtftruncation";N;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:39:"{50b22f5e-b4fc-4ac1-98df-7875c0a8000a}4";s:2:"id";s:5:"horde";}s:8:"opensuse";a:12:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:13:"rtftruncation";N;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50b22f8b-f134-4b75-98fc-198fc0a8000a}23";s:2:"id";s:8:"opensuse";}s:10:"@Calendar@";a:12:{s:5:"class";s:8:"Calendar";s:10:"windowsize";s:1:"4";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"4";s:10:"truncation";s:1:"7";s:13:"rtftruncation";N;s:11:"mimesupport";i:0;s:14:"mimetruncation";N;s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50b2d6c3-224c-4fb0-b6a5-2ef4c0a8000a}11";s:2:"id";s:10:"@Calendar@";}s:5:"INBOX";a:13:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:13:"rtftruncation";N;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72";s:2:"id";s:5:"INBOX";s:7:"synckey";s:40:"{50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72";}}s:14:"synckeycounter";a:5:{s:10:"@Contacts@";a:1:{s:40:"{50af8a31-ef4c-44b7-b92d-45b2c0a8000a}28";i:71;}s:5:"horde";a:1:{s:39:"{50b22f5e-b4fc-4ac1-98df-7875c0a8000a}3";i:1;}s:8:"opensuse";a:1:{s:40:"{50b22f8b-f134-4b75-98fc-198fc0a8000a}21";i:1;}s:10:"@Calendar@";a:1:{s:40:"{50b2d6c3-224c-4fb0-b6a5-2ef4c0a8000a}11";i:19;}s:5:"INBOX";a:1:{s:40:"{50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}71";i:1;}}s:13:"pingheartbeat";s:3:"470";}
2012-11-26T08:15:51+00:00 INFO: User XXXXX logged off
2012-11-26T08:15:52+00:00 INFO: Horde_Core_ActiveSync_Driver::authenticate() attempt for: XXXXX
2012-11-26T08:15:53+00:00 DEBUG: [androidc1500666295] SYNC request received for user XXXXX
2012-11-26T08:15:53+00:00 DEBUG: [androidc1500666295] loadDeviceInfo: XXXXX
2012-11-26T08:15:53+00:00 INFO: Request being handled for device: androidc1500666295 Supporting protocol version: 2.5
2012-11-26T08:15:53+00:00 INFO: [androidc1500666295] Handling SYNC command.
2012-11-26T08:15:53+00:00 DEBUG: [androidc1500666295] Checking policykey for device: 7465172901 user: XXXXX
2012-11-26T08:15:53+00:00 DEBUG: [androidc1500666295] Stored key: 7465172901
2012-11-26T08:15:53+00:00 DEBUG: Policykey: 7465172901 verified.
2012-11-26T08:15:53+00:00 DEBUG: I  <Synchronize>
2012-11-26T08:15:53+00:00 DEBUG: I   <Folders>
2012-11-26T08:15:53+00:00 DEBUG: I    <Folder>
2012-11-26T08:15:53+00:00 DEBUG: I     <FolderType>
2012-11-26T08:15:53+00:00 DEBUG: I      Email
2012-11-26T08:15:53+00:00 INFO: [androidc1500666295] Syncing folder class: Email
2012-11-26T08:15:53+00:00 DEBUG: I     </FolderType>
2012-11-26T08:15:53+00:00 DEBUG: I     <SyncKey>
2012-11-26T08:15:53+00:00 DEBUG: I      {50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72
2012-11-26T08:15:53+00:00 DEBUG: I     </SyncKey>
2012-11-26T08:15:53+00:00 DEBUG: I     <FolderId>
2012-11-26T08:15:53+00:00 DEBUG: I      INBOX
2012-11-26T08:15:53+00:00 INFO: [androidc1500666295] Folder server id: INBOX
2012-11-26T08:15:53+00:00 DEBUG: I     </FolderId>
2012-11-26T08:15:53+00:00 DEBUG: I     <DeletesAsMoves/>
2012-11-26T08:15:53+00:00 DEBUG: I     <GetChanges/>
2012-11-26T08:15:53+00:00 ERR: Unmatched content:
2012-11-26T08:15:53+00:00 ERR: Array
(
    [1] => 1
    [2] => GetChanges
    [4] => 0
)

2012-11-26T08:15:53+00:00 DEBUG: I     <WindowSize>
2012-11-26T08:15:53+00:00 ERR: Unmatched content:
2012-11-26T08:15:53+00:00 ERR: Array
(
    [1] => 1
    [2] => WindowSize
    [4] => 1
)

2012-11-26T08:15:53+00:00 DEBUG: I      5
2012-11-26T08:15:53+00:00 DEBUG: I     </WindowSize>
2012-11-26T08:15:53+00:00 DEBUG: I     <Options>
2012-11-26T08:15:53+00:00 DEBUG: I      <FilterType>
2012-11-26T08:15:53+00:00 DEBUG: I       0
2012-11-26T08:15:53+00:00 DEBUG: I      </FilterType>
2012-11-26T08:15:53+00:00 DEBUG: I      <MIMESupport>
2012-11-26T08:15:53+00:00 DEBUG: I       2
2012-11-26T08:15:53+00:00 DEBUG: I      </MIMESupport>
2012-11-26T08:15:53+00:00 DEBUG: I      <MIMETruncation>
2012-11-26T08:15:53+00:00 DEBUG: I       7
2012-11-26T08:15:53+00:00 DEBUG: I      </MIMETruncation>
2012-11-26T08:15:53+00:00 DEBUG: I     </Options>
2012-11-26T08:15:53+00:00 DEBUG: I    </Folder>
2012-11-26T08:15:53+00:00 DEBUG: I   </Folders>
2012-11-26T08:15:53+00:00 DEBUG: I  </Synchronize>
2012-11-26T08:15:53+00:00 DEBUG: Have syncable collections
2012-11-26T08:15:53+00:00 DEBUG: All synckeys confirmed. Continuing with SYNC
2012-11-26T08:15:53+00:00 DEBUG: Replacing SYNC_CACHE entry for user XXXXX and device androidc1500666295: a:12:{s:18:"confirmed_synckeys";a:0:{}s:17:"lasthbsyncstarted";i:1353917351;s:17:"lastsyncendnormal";i:1353917431;s:9:"lastuntil";i:1353917753;s:9:"timestamp";s:10:"1353917753";s:4:"wait";b:0;s:10:"hbinterval";b:0;s:7:"folders";a:0:{}s:9:"hierarchy";b:0;s:11:"collections";a:5:{s:10:"@Contacts@";a:13:{s:5:"class";s:8:"Contacts";s:10:"windowsize";s:1:"4";s:14:"deletesasmoves";b:1;s:10:"filtertype";N;s:10:"truncation";s:1:"7";s:13:"rtftruncation";N;s:11:"mimesupport";i:0;s:14:"mimetruncation";N;s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50af8a31-ef4c-44b7-b92d-45b2c0a8000a}28";s:2:"id";s:10:"@Contacts@";s:8:"pingable";b:1;}s:5:"horde";a:12:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:13:"rtftruncation";N;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:39:"{50b22f5e-b4fc-4ac1-98df-7875c0a8000a}4";s:2:"id";s:5:"horde";}s:8:"opensuse";a:12:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:13:"rtftruncation";N;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50b22f8b-f134-4b75-98fc-198fc0a8000a}23";s:2:"id";s:8:"opensuse";}s:10:"@Calendar@";a:12:{s:5:"class";s:8:"Calendar";s:10:"windowsize";s:1:"4";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"4";s:10:"truncation";s:1:"7";s:13:"rtftruncation";N;s:11:"mimesupport";i:0;s:14:"mimetruncation";N;s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50b2d6c3-224c-4fb0-b6a5-2ef4c0a8000a}11";s:2:"id";s:10:"@Calendar@";}s:5:"INBOX";a:13:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:13:"rtftruncation";N;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72";s:2:"id";s:5:"INBOX";s:7:"synckey";s:40:"{50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72";}}s:14:"synckeycounter";a:5:{s:10:"@Contacts@";a:1:{s:40:"{50af8a31-ef4c-44b7-b92d-45b2c0a8000a}28";i:71;}s:5:"horde";a:1:{s:39:"{50b22f5e-b4fc-4ac1-98df-7875c0a8000a}3";i:1;}s:8:"opensuse";a:1:{s:40:"{50b22f8b-f134-4b75-98fc-198fc0a8000a}21";i:1;}s:10:"@Calendar@";a:1:{s:40:"{50b2d6c3-224c-4fb0-b6a5-2ef4c0a8000a}11";i:19;}s:5:"INBOX";a:1:{s:40:"{50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72";i:1;}}s:13:"pingheartbeat";s:3:"470";}
2012-11-26T08:15:53+00:00 DEBUG: O  <Synchronize>
2012-11-26T08:15:53+00:00 DEBUG: O   <Status>
2012-11-26T08:15:53+00:00 DEBUG: O    1
2012-11-26T08:15:53+00:00 DEBUG: O   <Status/>
2012-11-26T08:15:53+00:00 DEBUG: [20583] Initializing state for collection: INBOX, synckey: {50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72
2012-11-26T08:15:53+00:00 DEBUG: [20583] Loading state for synckey {50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72
2012-11-26T08:15:54+00:00 DEBUG: [20583] Initializing message diff engine for INBOX
2012-11-26T08:15:54+00:00 DEBUG: Horde_Core_ActiveSync_Driver::getServerChanges(INBOX, 1353917751, 1353917754, 0, 0)
2012-11-26T08:15:54+00:00 DEBUG: IMAP status: Array
(
    [uidnext] => 38218
    [uidvalidity] => 1307656867
    [highestmodseq] => 1645
)

2012-11-26T08:15:54+00:00 DEBUG: [20583] Found 0 message changes in INBOX.
2012-11-26T08:15:54+00:00 DEBUG: O   <Folders>
2012-11-26T08:15:54+00:00 DEBUG: O    <Folder>
2012-11-26T08:15:54+00:00 DEBUG: O     <FolderType>
2012-11-26T08:15:54+00:00 DEBUG: O      Email
2012-11-26T08:15:54+00:00 DEBUG: O     <FolderType/>
2012-11-26T08:15:54+00:00 DEBUG: O     <SyncKey>
2012-11-26T08:15:54+00:00 DEBUG: O      {50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72
2012-11-26T08:15:54+00:00 DEBUG: O     <SyncKey/>
2012-11-26T08:15:54+00:00 DEBUG: O     <FolderId>
2012-11-26T08:15:54+00:00 DEBUG: O      INBOX
2012-11-26T08:15:54+00:00 DEBUG: O     <FolderId/>
2012-11-26T08:15:54+00:00 DEBUG: O     <Status>
2012-11-26T08:15:54+00:00 DEBUG: O      1
2012-11-26T08:15:54+00:00 DEBUG: O     <Status/>
2012-11-26T08:15:54+00:00 DEBUG: O    <Folder/>
2012-11-26T08:15:54+00:00 DEBUG: O   <Folders/>
2012-11-26T08:15:54+00:00 DEBUG: O  <Synchronize/>
2012-11-26T08:15:54+00:00 DEBUG: Replacing SYNC_CACHE entry for user XXXXX and device androidc1500666295: a:12:{s:18:"confirmed_synckeys";a:0:{}s:17:"lasthbsyncstarted";i:1353917351;s:17:"lastsyncendnormal";i:1353917431;s:9:"lastuntil";i:1353917753;s:9:"timestamp";s:10:"1353917754";s:4:"wait";b:0;s:10:"hbinterval";b:0;s:7:"folders";a:0:{}s:9:"hierarchy";b:0;s:11:"collections";a:5:{s:10:"@Contacts@";a:13:{s:5:"class";s:8:"Contacts";s:10:"windowsize";s:1:"4";s:14:"deletesasmoves";b:1;s:10:"filtertype";N;s:10:"truncation";s:1:"7";s:13:"rtftruncation";N;s:11:"mimesupport";i:0;s:14:"mimetruncation";N;s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50af8a31-ef4c-44b7-b92d-45b2c0a8000a}28";s:2:"id";s:10:"@Contacts@";s:8:"pingable";b:1;}s:5:"horde";a:12:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:13:"rtftruncation";N;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:39:"{50b22f5e-b4fc-4ac1-98df-7875c0a8000a}4";s:2:"id";s:5:"horde";}s:8:"opensuse";a:12:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:13:"rtftruncation";N;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50b22f8b-f134-4b75-98fc-198fc0a8000a}23";s:2:"id";s:8:"opensuse";}s:10:"@Calendar@";a:12:{s:5:"class";s:8:"Calendar";s:10:"windowsize";s:1:"4";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"4";s:10:"truncation";s:1:"7";s:13:"rtftruncation";N;s:11:"mimesupport";i:0;s:14:"mimetruncation";N;s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50b2d6c3-224c-4fb0-b6a5-2ef4c0a8000a}11";s:2:"id";s:10:"@Calendar@";}s:5:"INBOX";a:13:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:13:"rtftruncation";N;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72";s:2:"id";s:5:"INBOX";s:7:"synckey";s:40:"{50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72";}}s:14:"synckeycounter";a:5:{s:10:"@Contacts@";a:1:{s:40:"{50af8a31-ef4c-44b7-b92d-45b2c0a8000a}28";i:71;}s:5:"horde";a:1:{s:39:"{50b22f5e-b4fc-4ac1-98df-7875c0a8000a}3";i:1;}s:8:"opensuse";a:1:{s:40:"{50b22f8b-f134-4b75-98fc-198fc0a8000a}21";i:1;}s:10:"@Calendar@";a:1:{s:40:"{50b2d6c3-224c-4fb0-b6a5-2ef4c0a8000a}11";i:19;}s:5:"INBOX";a:1:{s:40:"{50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72";i:1;}}s:13:"pingheartbeat";s:3:"470";}
2012-11-26T08:15:54+00:00 INFO: User XXXXX logged off

-------------- next part --------------
2012-11-26T08:21:15+00:00 INFO: Horde_Core_ActiveSync_Driver::authenticate() attempt for: XXXXX
2012-11-26T08:21:15+00:00 INFO: Horde_Core_ActiveSync_Driver::authenticate() attempt for: XXXXX
2012-11-26T08:21:15+00:00 DEBUG: [androidc1500666295] PING request received for user XXXXX
2012-11-26T08:21:15+00:00 DEBUG: [androidc1500666295] SYNC request received for user XXXXX
2012-11-26T08:21:15+00:00 DEBUG: [androidc1500666295] loadDeviceInfo: XXXXX
2012-11-26T08:21:15+00:00 DEBUG: [androidc1500666295] loadDeviceInfo: XXXXX
2012-11-26T08:21:15+00:00 INFO: Request being handled for device: androidc1500666295 Supporting protocol version: 2.5
2012-11-26T08:21:15+00:00 INFO: [23392] PING received at timestamp: 1353918075.
2012-11-26T08:21:15+00:00 INFO: Request being handled for device: androidc1500666295 Supporting protocol version: 2.5
2012-11-26T08:21:15+00:00 INFO: [androidc1500666295] Handling SYNC command.
2012-11-26T08:21:15+00:00 DEBUG: [androidc1500666295] Checking policykey for device: 7465172901 user: XXXXX
2012-11-26T08:21:15+00:00 DEBUG: [androidc1500666295] Stored key: 7465172901
2012-11-26T08:21:15+00:00 DEBUG: Policykey: 7465172901 verified.
2012-11-26T08:21:15+00:00 DEBUG: I  <Synchronize>
2012-11-26T08:21:15+00:00 DEBUG: I   <Folders>
2012-11-26T08:21:15+00:00 DEBUG: I    <Folder>
2012-11-26T08:21:15+00:00 DEBUG: I     <FolderType>
2012-11-26T08:21:15+00:00 DEBUG: I      Email
2012-11-26T08:21:15+00:00 INFO: [androidc1500666295] Syncing folder class: Email
2012-11-26T08:21:15+00:00 DEBUG: I     </FolderType>
2012-11-26T08:21:15+00:00 DEBUG: I     <SyncKey>
2012-11-26T08:21:15+00:00 DEBUG: I      {50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72
2012-11-26T08:21:15+00:00 DEBUG: I     </SyncKey>
2012-11-26T08:21:15+00:00 DEBUG: I     <FolderId>
2012-11-26T08:21:15+00:00 DEBUG: I      INBOX
2012-11-26T08:21:15+00:00 INFO: [androidc1500666295] Folder server id: INBOX
2012-11-26T08:21:15+00:00 DEBUG: I     </FolderId>
2012-11-26T08:21:15+00:00 DEBUG: I     <DeletesAsMoves/>
2012-11-26T08:21:15+00:00 DEBUG: I     <GetChanges/>
2012-11-26T08:21:15+00:00 ERR: Unmatched content:
2012-11-26T08:21:15+00:00 ERR: Array
(
    [1] => 1
    [2] => GetChanges
    [4] => 0
)

2012-11-26T08:21:15+00:00 DEBUG: I     <WindowSize>
2012-11-26T08:21:15+00:00 ERR: Unmatched content:
2012-11-26T08:21:15+00:00 ERR: Array
(
    [1] => 1
    [2] => WindowSize
    [4] => 1
)

2012-11-26T08:21:15+00:00 DEBUG: I      5
2012-11-26T08:21:15+00:00 DEBUG: I     </WindowSize>
2012-11-26T08:21:15+00:00 DEBUG: I     <Options>
2012-11-26T08:21:15+00:00 DEBUG: I      <FilterType>
2012-11-26T08:21:15+00:00 DEBUG: I  <Ping:Ping>
2012-11-26T08:21:15+00:00 DEBUG: I       0
2012-11-26T08:21:15+00:00 DEBUG: I   <Ping:HeartbeatInterval>
2012-11-26T08:21:15+00:00 DEBUG: I      </FilterType>
2012-11-26T08:21:15+00:00 DEBUG: I      <MIMESupport>
2012-11-26T08:21:15+00:00 DEBUG: I    470
2012-11-26T08:21:15+00:00 DEBUG: I   </Ping:HeartbeatInterval>
2012-11-26T08:21:15+00:00 DEBUG: I       2
2012-11-26T08:21:15+00:00 DEBUG: I      </MIMESupport>
2012-11-26T08:21:15+00:00 DEBUG: I   <Ping:Folders>
2012-11-26T08:21:15+00:00 DEBUG: I      <MIMETruncation>
2012-11-26T08:21:15+00:00 DEBUG: I    <Ping:Folder>
2012-11-26T08:21:15+00:00 DEBUG: I       7
2012-11-26T08:21:15+00:00 DEBUG: I     <Ping:ServerEntryId>
2012-11-26T08:21:15+00:00 DEBUG: I      </MIMETruncation>
2012-11-26T08:21:15+00:00 DEBUG: I      @Contacts@
2012-11-26T08:21:15+00:00 DEBUG: I     </Options>
2012-11-26T08:21:15+00:00 DEBUG: I     </Ping:ServerEntryId>
2012-11-26T08:21:15+00:00 DEBUG: I    </Folder>
2012-11-26T08:21:15+00:00 DEBUG: I     <Ping:FolderType>
2012-11-26T08:21:15+00:00 ERR: Incrementing counter, we saw this synckey before.
2012-11-26T08:21:15+00:00 DEBUG: I      Contacts
2012-11-26T08:21:15+00:00 DEBUG: I     </Ping:FolderType>
2012-11-26T08:21:15+00:00 DEBUG: I   </Folders>
2012-11-26T08:21:15+00:00 DEBUG: I    </Ping:Folder>
2012-11-26T08:21:15+00:00 DEBUG: I  </Synchronize>
2012-11-26T08:21:15+00:00 DEBUG: I    <Ping:Folder>
2012-11-26T08:21:15+00:00 DEBUG: Have syncable collections
2012-11-26T08:21:15+00:00 DEBUG: I     <Ping:ServerEntryId>
2012-11-26T08:21:15+00:00 DEBUG: All synckeys confirmed. Continuing with SYNC
2012-11-26T08:21:15+00:00 DEBUG: I      @Calendar@
2012-11-26T08:21:15+00:00 DEBUG: I     </Ping:ServerEntryId>
2012-11-26T08:21:15+00:00 DEBUG: I     <Ping:FolderType>
2012-11-26T08:21:15+00:00 DEBUG: I      Calendar
2012-11-26T08:21:15+00:00 DEBUG: I     </Ping:FolderType>
2012-11-26T08:21:15+00:00 DEBUG: I    </Ping:Folder>
2012-11-26T08:21:15+00:00 DEBUG: I   </Ping:Folders>
2012-11-26T08:21:15+00:00 DEBUG: I  </Ping:Ping>
2012-11-26T08:21:15+00:00 INFO: [23392] Waiting for changes (heartbeat interval: 470)
2012-11-26T08:21:15+00:00 DEBUG: Replacing SYNC_CACHE entry for user XXXXX and device androidc1500666295: a:12:{s:18:"confirmed_synckeys";a:0:{}s:17:"lasthbsyncstarted";i:1353917450;s:17:"lastsyncendnormal";i:1353917930;s:9:"lastuntil";i:1353918075;s:9:"timestamp";s:10:"1353918075";s:4:"wait";b:0;s:10:"hbinterval";b:0;s:7:"folders";a:0:{}s:9:"hierarchy";b:0;s:11:"collections";a:5:{s:10:"@Contacts@";a:13:{s:5:"class";s:8:"Contacts";s:10:"windowsize";s:1:"4";s:14:"deletesasmoves";b:1;s:10:"filtertype";N;s:10:"truncation";s:1:"7";s:13:"rtftruncation";N;s:11:"mimesupport";i:0;s:14:"mimetruncation";N;s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50af8a31-ef4c-44b7-b92d-45b2c0a8000a}28";s:2:"id";s:10:"@Contacts@";s:8:"pingable";b:1;}s:5:"horde";a:12:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:13:"rtftruncation";N;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:39:"{50b22f5e-b4fc-4ac1-98df-7875c0a8000a}4";s:2:"id";s:5:"horde";}s:8:"opensuse";a:12:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:13:"rtftruncation";N;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50b22f8b-f134-4b75-98fc-198fc0a8000a}23";s:2:"id";s:8:"opensuse";}s:10:"@Calendar@";a:12:{s:5:"class";s:8:"Calendar";s:10:"windowsize";s:1:"4";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"4";s:10:"truncation";s:1:"7";s:13:"rtftruncation";N;s:11:"mimesupport";i:0;s:14:"mimetruncation";N;s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50b2d6c3-224c-4fb0-b6a5-2ef4c0a8000a}11";s:2:"id";s:10:"@Calendar@";}s:5:"INBOX";a:13:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:13:"rtftruncation";N;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72";s:2:"id";s:5:"INBOX";s:7:"synckey";s:40:"{50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72";}}s:14:"synckeycounter";a:5:{s:10:"@Contacts@";a:1:{s:40:"{50af8a31-ef4c-44b7-b92d-45b2c0a8000a}28";i:71;}s:5:"horde";a:1:{s:39:"{50b22f5e-b4fc-4ac1-98df-7875c0a8000a}3";i:1;}s:8:"opensuse";a:1:{s:40:"{50b22f8b-f134-4b75-98fc-198fc0a8000a}21";i:1;}s:10:"@Calendar@";a:1:{s:40:"{50b2d6c3-224c-4fb0-b6a5-2ef4c0a8000a}11";i:18;}s:5:"INBOX";a:1:{s:40:"{50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72";i:2;}}s:13:"pingheartbeat";s:3:"470";}
2012-11-26T08:21:15+00:00 DEBUG: [23392] Initializing state for collection: @Contacts@, synckey: {50af8a31-ef4c-44b7-b92d-45b2c0a8000a}28
2012-11-26T08:21:15+00:00 DEBUG: [23392] Loading state for synckey {50af8a31-ef4c-44b7-b92d-45b2c0a8000a}28
2012-11-26T08:21:16+00:00 DEBUG: [23392] Initializing message diff engine for @Contacts@
2012-11-26T08:21:16+00:00 DEBUG: Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 1353681572, 1353918076, 0, 1)
2012-11-26T08:21:16+00:00 DEBUG: O  <Synchronize>
2012-11-26T08:21:16+00:00 DEBUG: O   <Status>
2012-11-26T08:21:16+00:00 DEBUG: O    1
2012-11-26T08:21:16+00:00 DEBUG: O   <Status/>
2012-11-26T08:21:16+00:00 DEBUG: [21503] Initializing state for collection: INBOX, synckey: {50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72
2012-11-26T08:21:16+00:00 DEBUG: [21503] Loading state for synckey {50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72
2012-11-26T08:21:16+00:00 DEBUG: [21503] Initializing message diff engine for INBOX
2012-11-26T08:21:16+00:00 DEBUG: Horde_Core_ActiveSync_Driver::getServerChanges(INBOX, 1353917751, 1353918076, 0, 0)
2012-11-26T08:21:16+00:00 DEBUG: [23392] Found 0 message changes in @Contacts at .
2012-11-26T08:21:16+00:00 DEBUG: [23392] Initializing state for collection: @Calendar@, synckey: {50b2d6c3-224c-4fb0-b6a5-2ef4c0a8000a}11
2012-11-26T08:21:16+00:00 DEBUG: [23392] Loading state for synckey {50b2d6c3-224c-4fb0-b6a5-2ef4c0a8000a}11
2012-11-26T08:21:16+00:00 DEBUG: [23392] Initializing message diff engine for @Calendar@
2012-11-26T08:21:16+00:00 DEBUG: Horde_Core_ActiveSync_Driver::getServerChanges(@Calendar@, 1353897682, 1353918076, 0, 1)
2012-11-26T08:21:16+00:00 DEBUG: IMAP status: Array
(
    [uidnext] => 38218
    [uidvalidity] => 1307656867
    [highestmodseq] => 1664
)

2012-11-26T08:21:16+00:00 DEBUG: CONDSTORE and CHANGES
2012-11-26T08:21:16+00:00 DEBUG: [21503] Found 3 message changes in INBOX.
2012-11-26T08:21:16+00:00 DEBUG: [21503] Checking for PIM initiated changes.
2012-11-26T08:21:16+00:00 DEBUG: _isPIMChange: 38214, Array
(
    [read] => 1
)
, flags
2012-11-26T08:21:16+00:00 DEBUG: [21503] Ignoring PIM initiated flag change for 38214
2012-11-26T08:21:16+00:00 DEBUG: _isPIMChange: 38215, Array
(
    [read] => 1
)
, flags
2012-11-26T08:21:16+00:00 DEBUG: [21503] Ignoring PIM initiated flag change for 38215
2012-11-26T08:21:16+00:00 DEBUG: _isPIMChange: 38217, Array
(
    [read] => 1
)
, flags
2012-11-26T08:21:16+00:00 DEBUG: [21503] Ignoring PIM initiated flag change for 38217
2012-11-26T08:21:16+00:00 DEBUG: O   <Folders>
2012-11-26T08:21:16+00:00 DEBUG: O    <Folder>
2012-11-26T08:21:16+00:00 DEBUG: O     <FolderType>
2012-11-26T08:21:16+00:00 DEBUG: O      Email
2012-11-26T08:21:16+00:00 DEBUG: O     <FolderType/>
2012-11-26T08:21:16+00:00 DEBUG: O     <SyncKey>
2012-11-26T08:21:16+00:00 DEBUG: O      {50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72
2012-11-26T08:21:16+00:00 DEBUG: O     <SyncKey/>
2012-11-26T08:21:16+00:00 DEBUG: O     <FolderId>
2012-11-26T08:21:16+00:00 DEBUG: O      INBOX
2012-11-26T08:21:16+00:00 DEBUG: O     <FolderId/>
2012-11-26T08:21:16+00:00 DEBUG: O     <Status>
2012-11-26T08:21:16+00:00 DEBUG: O      1
2012-11-26T08:21:16+00:00 DEBUG: O     <Status/>
2012-11-26T08:21:16+00:00 DEBUG: O    <Folder/>
2012-11-26T08:21:16+00:00 DEBUG: O   <Folders/>
2012-11-26T08:21:16+00:00 DEBUG: O  <Synchronize/>
2012-11-26T08:21:16+00:00 DEBUG: Replacing SYNC_CACHE entry for user XXXXX and device androidc1500666295: a:12:{s:18:"confirmed_synckeys";a:0:{}s:17:"lasthbsyncstarted";i:1353917450;s:17:"lastsyncendnormal";i:1353917930;s:9:"lastuntil";i:1353918075;s:9:"timestamp";s:10:"1353918076";s:4:"wait";b:0;s:10:"hbinterval";b:0;s:7:"folders";a:0:{}s:9:"hierarchy";b:0;s:11:"collections";a:5:{s:10:"@Contacts@";a:13:{s:5:"class";s:8:"Contacts";s:10:"windowsize";s:1:"4";s:14:"deletesasmoves";b:1;s:10:"filtertype";N;s:10:"truncation";s:1:"7";s:13:"rtftruncation";N;s:11:"mimesupport";i:0;s:14:"mimetruncation";N;s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50af8a31-ef4c-44b7-b92d-45b2c0a8000a}28";s:2:"id";s:10:"@Contacts@";s:8:"pingable";b:1;}s:5:"horde";a:12:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:13:"rtftruncation";N;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:39:"{50b22f5e-b4fc-4ac1-98df-7875c0a8000a}4";s:2:"id";s:5:"horde";}s:8:"opensuse";a:12:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:13:"rtftruncation";N;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50b22f8b-f134-4b75-98fc-198fc0a8000a}23";s:2:"id";s:8:"opensuse";}s:10:"@Calendar@";a:12:{s:5:"class";s:8:"Calendar";s:10:"windowsize";s:1:"4";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"4";s:10:"truncation";s:1:"7";s:13:"rtftruncation";N;s:11:"mimesupport";i:0;s:14:"mimetruncation";N;s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50b2d6c3-224c-4fb0-b6a5-2ef4c0a8000a}11";s:2:"id";s:10:"@Calendar@";}s:5:"INBOX";a:13:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:13:"rtftruncation";N;s:11:"mimesupport";s:1:"2";s:14:"mimetruncation";s:1:"7";s:8:"conflict";i:1;s:9:"bodyprefs";N;s:11:"lastsynckey";s:40:"{50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72";s:2:"id";s:5:"INBOX";s:7:"synckey";s:40:"{50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72";}}s:14:"synckeycounter";a:5:{s:10:"@Contacts@";a:1:{s:40:"{50af8a31-ef4c-44b7-b92d-45b2c0a8000a}28";i:71;}s:5:"horde";a:1:{s:39:"{50b22f5e-b4fc-4ac1-98df-7875c0a8000a}3";i:1;}s:8:"opensuse";a:1:{s:40:"{50b22f8b-f134-4b75-98fc-198fc0a8000a}21";i:1;}s:10:"@Calendar@";a:1:{s:40:"{50b2d6c3-224c-4fb0-b6a5-2ef4c0a8000a}11";i:18;}s:5:"INBOX";a:1:{s:40:"{50af8a2f-f9b0-49f9-ab16-36e3c0a8000a}72";i:2;}}s:13:"pingheartbeat";s:3:"470";}
2012-11-26T08:21:17+00:00 INFO: User XXXXX logged off
2012-11-26T09:21:17+01:00 DEBUG: [23392] Found 0 message changes in @Calendar at .



More information about the horde mailing list