2016-02-13T12:30:01+10:00 INFO: [3455] Horde_Core_ActiveSync_Driver::authenticate() attempt for deb.tony
2016-02-13T12:30:02+10:00 INFO: [3455] SYNC request received for user deb.tony
2016-02-13T12:30:02+10:00 INFO: [3455] Device entry exists for SEC1288365EE7640, updating userAgent, version, and supported.
2016-02-13T12:30:02+10:00 INFO: [3455] Request being handled for device: SEC1288365EE7640, Supporting protocol version: 14.1, Using Horde_ActiveSync v2.30.6
2016-02-13T12:30:02+10:00 INFO: [3455] GET VARIABLES: Array
(
    [Cmd] => Sync
    [User] => deb.tony
    [DeviceId] => SEC1288365EE7640
    [DeviceType] => SAMSUNGSMG900I
)

2016-02-13T12:30:02+10:00 INFO: [3455] Handling SYNC command.
2016-02-13T12:30:02+10:00 INFO: [3455] Checking policykey for device: SEC1288365EE7640 user: deb.tony
2016-02-13T12:30:02+10:00 INFO: [3455] Stored key: 4389867597
2016-02-13T12:30:02+10:00 INFO: [3455] Policykey: 4389867597 verified.
2016-02-13T12:30:02+10:00 INFO: [3455] Creating new Horde_ActiveSync_SyncCache.
2016-02-13T12:30:02+10:00 DEBUG: [3455] I  <Synchronize>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I   <Folders>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I    <Folder>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I     <SyncKey>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I       {56be94d5-ecd4-46dc-a3fb-0d68c0a801e6}2
2016-02-13T12:30:02+10:00 DEBUG: [3455] I     </SyncKey>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I     <FolderId>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I       F1bb66369
2016-02-13T12:30:02+10:00 DEBUG: [3455] I     </FolderId>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I     <DeletesAsMoves>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I       1
2016-02-13T12:30:02+10:00 DEBUG: [3455] I     </DeletesAsMoves>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I     <GetChanges />
2016-02-13T12:30:02+10:00 DEBUG: [3455] I     <WindowSize>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I       50
2016-02-13T12:30:02+10:00 DEBUG: [3455] I     </WindowSize>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I     <Options>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I      <FilterType>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I        4
2016-02-13T12:30:02+10:00 DEBUG: [3455] I      </FilterType>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I      <RightsManagement:RightsManagementSupport>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I        1
2016-02-13T12:30:02+10:00 DEBUG: [3455] I      </RightsManagement:RightsManagementSupport>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I      <AirSyncBase:BodyPreference>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I       <AirSyncBase:Type>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I         2
2016-02-13T12:30:02+10:00 DEBUG: [3455] I       </AirSyncBase:Type>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I       <AirSyncBase:TruncationSize>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I         51200
2016-02-13T12:30:02+10:00 DEBUG: [3455] I       </AirSyncBase:TruncationSize>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I      </AirSyncBase:BodyPreference>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I      <AirSyncBase:BodyPreference>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I       <AirSyncBase:Type>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I         4
2016-02-13T12:30:02+10:00 DEBUG: [3455] I       </AirSyncBase:Type>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I      </AirSyncBase:BodyPreference>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I     </Options>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I    </Folder>
2016-02-13T12:30:02+10:00 INFO: [3455] Obtaining collection class of Email for collection id F1bb66369
2016-02-13T12:30:02+10:00 INFO: [3455] Collection added to collection handler: collection: INBOX, synckey: {56be94d5-ecd4-46dc-a3fb-0d68c0a801e6}2.
2016-02-13T12:30:02+10:00 DEBUG: [3455] I   </Folders>
2016-02-13T12:30:02+10:00 DEBUG: [3455] I  </Synchronize>
2016-02-13T12:30:02+10:00 INFO: [3455] Loading default OPTIONS for F1bb66369 collection.
2016-02-13T12:30:02+10:00 INFO: [3455] Clearing all collection synckeys from the cache.
2016-02-13T12:30:02+10:00 INFO: Have syncable collections
2016-02-13T12:30:02+10:00 INFO: [3455] Replacing SYNC_CACHE entry for user deb.tony and device SEC1288365EE7640: a:11:{s:18:"confirmed_synckeys";a:0:{}s:17:"lasthbsyncstarted";i:1455330543;s:17:"lastsyncendnormal";i:1455330601;s:9:"timestamp";s:10:"1455330602";s:4:"wait";b:0;s:10:"hbinterval";s:3:"470";s:7:"folders";a:11:{s:9:"Tc92bd0d0";a:2:{s:5:"class";s:5:"Tasks";s:8:"serverid";s:7:"@Tasks@";}s:2:"RI";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:2:"RI";}s:9:"N7ee5b6b1";a:2:{s:5:"class";s:5:"Notes";s:8:"serverid";s:7:"@Notes@";}s:9:"Fef4a01cc";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:12:"INBOX/Drafts";}s:9:"F85674ef5";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:11:"INBOX/Trash";}s:9:"F6c01bf3d";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:10:"INBOX/Sent";}s:9:"F1fb92200";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:18:"INBOX/Saved Emails";}s:9:"F1bb66369";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:5:"INBOX";}s:9:"F13d28d0e";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:10:"INBOX/Spam";}s:9:"C377e514f";a:2:{s:5:"class";s:8:"Contacts";s:8:"serverid";s:10:"@Contacts@";}s:9:"Ab6c6ad34";a:2:{s:5:"class";s:8:"Calendar";s:8:"serverid";s:10:"@Calendar@";}}s:9:"hierarchy";s:39:"{56be94d2-7664-463b-bd5e-19e2c0a801e6}1";s:11:"collections";a:2:{s:2:"RI";a:9:{s:5:"class";s:2:"RI";s:10:"windowsize";s:2:"10";s:11:"mimesupport";i:0;s:8:"conflict";i:1;s:9:"bodyprefs";a:0:{}s:8:"serverid";s:2:"RI";s:11:"lastsynckey";s:39:"{56be94d5-8794-4c2f-a890-0d61c0a801e6}2";s:14:"deletesasmoves";b:1;s:8:"pingable";b:1;}s:9:"F1bb66369";a:10:{s:5:"class";s:5:"Email";s:10:"windowsize";s:2:"50";s:11:"mimesupport";i:0;s:8:"conflict";i:1;s:9:"bodyprefs";a:2:{i:2;a:2:{s:4:"type";s:1:"2";s:14:"truncationsize";s:5:"51200";}i:4;a:1:{s:4:"type";s:1:"4";}}s:8:"serverid";s:5:"INBOX";s:11:"lastsynckey";s:39:"{56be94d5-ecd4-46dc-a3fb-0d68c0a801e6}2";s:14:"deletesasmoves";s:1:"1";s:10:"filtertype";s:1:"4";s:8:"pingable";b:1;}}s:13:"pingheartbeat";b:0;s:14:"synckeycounter";a:0:{}}
2016-02-13T12:30:03+10:00 INFO: [3455] All synckeys confirmed. Continuing with SYNC
2016-02-13T12:30:03+10:00 INFO: [3455] Completed parsing incoming request. Peak memory usage: 16252928.
2016-02-13T12:30:03+10:00 DEBUG: [3455] O  <Synchronize>
2016-02-13T12:30:03+10:00 DEBUG: [3455] O   <Status>
2016-02-13T12:30:03+10:00 DEBUG: [3455] O    1
2016-02-13T12:30:03+10:00 DEBUG: [3455] O   </Status>
2016-02-13T12:30:03+10:00 INFO: [3455] Initializing state for collection: INBOX, synckey: {56be94d5-ecd4-46dc-a3fb-0d68c0a801e6}2
2016-02-13T12:30:03+10:00 INFO: [3455] Loading state for synckey {56be94d5-ecd4-46dc-a3fb-0d68c0a801e6}2
2016-02-13T12:30:03+10:00 INFO: [3455] Initializing message diff engine for F1bb66369 (INBOX)
2016-02-13T12:30:03+10:00 INFO: [3455] Horde_Core_ActiveSync_Driver::getSyncStamp(INBOX, 1455330520);
2016-02-13T12:30:03+10:00 INFO: [3455] Using SYNCSTAMP 1455330603 for F1bb66369.
2016-02-13T12:30:03+10:00 INFO: [3455] Horde_Core_ActiveSync_Driver::getServerChanges(INBOX, 1455330520, 1455330603, 1454121003, 0, 1, 100, )
2016-02-13T12:30:03+10:00 INFO: [3455] INBOX IMAP PREVIOUS MODSEQ: 0
2016-02-13T12:30:03+10:00 INFO: [3455] IMAP status: a:4:{s:13:"highestmodseq";i:0;s:8:"messages";i:102;s:7:"uidnext";i:3692;s:11:"uidvalidity";i:1423296336;}
2016-02-13T12:30:03+10:00 INFO: [3455] NO CONDSTORE or per mailbox MODSEQ. minuid: 3612, total_messages: 102
2016-02-13T12:30:03+10:00 INFO: [3455] Found 0 message changes in F1bb66369.
2016-02-13T12:30:03+10:00 DEBUG: [3455] O   <Folders>
2016-02-13T12:30:03+10:00 DEBUG: [3455] O    <Folder>
2016-02-13T12:30:03+10:00 DEBUG: [3455] O     <SyncKey>
2016-02-13T12:30:03+10:00 DEBUG: [3455] O      {56be94d5-ecd4-46dc-a3fb-0d68c0a801e6}2
2016-02-13T12:30:03+10:00 DEBUG: [3455] O     </SyncKey>
2016-02-13T12:30:03+10:00 DEBUG: [3455] O     <FolderId>
2016-02-13T12:30:03+10:00 DEBUG: [3455] O      F1bb66369
2016-02-13T12:30:03+10:00 DEBUG: [3455] O     </FolderId>
2016-02-13T12:30:03+10:00 DEBUG: [3455] O     <Status>
2016-02-13T12:30:03+10:00 DEBUG: [3455] O      1
2016-02-13T12:30:03+10:00 DEBUG: [3455] O     </Status>
2016-02-13T12:30:03+10:00 DEBUG: [3455] O    </Folder>
2016-02-13T12:30:03+10:00 INFO: [3455] Collection output peak memory usage: 16777216
2016-02-13T12:30:03+10:00 DEBUG: [3455] O   </Folders>
2016-02-13T12:30:03+10:00 DEBUG: [3455] O  </Synchronize>
2016-02-13T12:30:03+10:00 INFO: [3455] Replacing SYNC_CACHE entry for user deb.tony and device SEC1288365EE7640: a:11:{s:18:"confirmed_synckeys";a:0:{}s:17:"lasthbsyncstarted";i:1455330543;s:17:"lastsyncendnormal";i:1455330603;s:9:"timestamp";s:10:"1455330603";s:4:"wait";b:0;s:10:"hbinterval";s:3:"470";s:7:"folders";a:11:{s:9:"Tc92bd0d0";a:2:{s:5:"class";s:5:"Tasks";s:8:"serverid";s:7:"@Tasks@";}s:2:"RI";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:2:"RI";}s:9:"N7ee5b6b1";a:2:{s:5:"class";s:5:"Notes";s:8:"serverid";s:7:"@Notes@";}s:9:"Fef4a01cc";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:12:"INBOX/Drafts";}s:9:"F85674ef5";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:11:"INBOX/Trash";}s:9:"F6c01bf3d";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:10:"INBOX/Sent";}s:9:"F1fb92200";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:18:"INBOX/Saved Emails";}s:9:"F1bb66369";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:5:"INBOX";}s:9:"F13d28d0e";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:10:"INBOX/Spam";}s:9:"C377e514f";a:2:{s:5:"class";s:8:"Contacts";s:8:"serverid";s:10:"@Contacts@";}s:9:"Ab6c6ad34";a:2:{s:5:"class";s:8:"Calendar";s:8:"serverid";s:10:"@Calendar@";}}s:9:"hierarchy";s:39:"{56be94d2-7664-463b-bd5e-19e2c0a801e6}1";s:11:"collections";a:2:{s:2:"RI";a:9:{s:5:"class";s:2:"RI";s:10:"windowsize";s:2:"10";s:11:"mimesupport";i:0;s:8:"conflict";i:1;s:9:"bodyprefs";a:0:{}s:8:"serverid";s:2:"RI";s:11:"lastsynckey";s:39:"{56be94d5-8794-4c2f-a890-0d61c0a801e6}2";s:14:"deletesasmoves";b:1;s:8:"pingable";b:1;}s:9:"F1bb66369";a:10:{s:5:"class";s:5:"Email";s:10:"windowsize";s:2:"50";s:11:"mimesupport";i:0;s:8:"conflict";i:1;s:9:"bodyprefs";a:2:{i:2;a:2:{s:4:"type";s:1:"2";s:14:"truncationsize";s:5:"51200";}i:4;a:1:{s:4:"type";s:1:"4";}}s:8:"serverid";s:5:"INBOX";s:11:"lastsynckey";s:39:"{56be94d5-ecd4-46dc-a3fb-0d68c0a801e6}2";s:14:"deletesasmoves";s:1:"1";s:10:"filtertype";s:1:"4";s:8:"pingable";b:1;}}s:13:"pingheartbeat";b:0;s:14:"synckeycounter";a:0:{}}
2016-02-13T12:30:03+10:00 INFO: [3455] Maximum memory usage for ActiveSync request: 16777216 bytes.