2015-07-08T19:58:28+00:00 INFO: [21654] Horde_Core_ActiveSync_Driver::authenticate() attempt for mmx.test@netdmon.de
2015-07-08T19:58:28+00:00 INFO: [21654] FOLDERSYNC request received for user mmx.test@netdmon.de
2015-07-08T19:58:28+00:00 INFO: [21654] Device entry exists for ANDROID949141856202, updating userAgent and version.
2015-07-08T19:58:28+00:00 INFO: [21654] Request being handled for device: ANDROID949141856202, Supporting protocol version: 14.1, Using Horde_ActiveSync v2.28.5
2015-07-08T19:58:28+00:00 INFO: [21654] GET VARIABLES: Array
(
    [Cmd] => FolderSync
    [User] => mmx.test@netdmon.de
    [DeviceId] => android949141856202
    [DeviceType] => Android
)

2015-07-08T19:58:28+00:00 INFO: [21654] Handling FOLDERSYNC command.
2015-07-08T19:58:28+00:00 INFO: [21654] Checking policykey for device: ANDROID949141856202 user: mmx.test@netdmon.de
2015-07-08T19:58:28+00:00 INFO: [21654] Policykey: 0 verified.
2015-07-08T19:58:28+00:00 DEBUG: [21654] I  <FolderHierarchy:FolderSync>
2015-07-08T19:58:28+00:00 DEBUG: [21654] I   <FolderHierarchy:SyncKey>
2015-07-08T19:58:28+00:00 DEBUG: [21654] I     0
2015-07-08T19:58:28+00:00 DEBUG: [21654] I   </FolderHierarchy:SyncKey>
2015-07-08T19:58:28+00:00 INFO: [21654] Creating new Horde_ActiveSync_SyncCache.
2015-07-08T19:58:28+00:00 NOTICE: [21654] Horde_ActiveSync_State_Base::loadState: clearing folder state.
2015-07-08T19:58:28+00:00 INFO: [21654] Resetting device state for device: ANDROID949141856202, user: mmx.test@netdmon.de, and collection: foldersync.
2015-07-08T19:58:28+00:00 INFO: [21654] Creating new Horde_ActiveSync_SyncCache.
2015-07-08T19:58:28+00:00 NOTICE: [21654] Clearing foldersync state from synccache.
2015-07-08T19:58:28+00:00 INFO: [21654] Clearing collections data from cache.
2015-07-08T19:58:28+00:00 INFO: [21654] Replacing SYNC_CACHE entry for user mmx.test@netdmon.de and device ANDROID949141856202: a:11:{s:18:"confirmed_synckeys";a:1:{s:39:"{559d806a-b974-4601-9ce0-54a05850ca18}2";b:1;}s:17:"lasthbsyncstarted";b:0;s:17:"lastsyncendnormal";i:1436385387;s:9:"timestamp";s:10:"1436385508";s:4:"wait";b:0;s:10:"hbinterval";b:0;s:7:"folders";a:0:{}s:9:"hierarchy";s:1:"0";s:11:"collections";a:0:{}s:13:"pingheartbeat";b:0;s:14:"synckeycounter";a:0:{}}
2015-07-08T19:58:28+00:00 DEBUG: [21654] I  </FolderHierarchy:FolderSync>
2015-07-08T19:58:28+00:00 INFO: [21654] Initializing folder diff engine
2015-07-08T19:58:28+00:00 INFO: [21654] Horde_Core_ActiveSync_Driver::getFolderList()
2015-07-08T19:58:28+00:00 INFO: [21654] Creating new folder uuid for @Calendar@: Ac729ec19
2015-07-08T19:58:28+00:00 INFO: [21654] Creating new folder uuid for @Contacts@: C7161b4e6
2015-07-08T19:58:28+00:00 INFO: [21654] Creating new folder uuid for @Tasks@: Tece8c7d2
2015-07-08T19:58:28+00:00 INFO: [21654] Creating new folder uuid for @Notes@: N540410ba
2015-07-08T19:58:28+00:00 INFO: [21654] Polling Horde_Core_ActiveSync_Driver::_getMailFolders()
2015-07-08T19:58:28+00:00 INFO: [21654] Creating new folder uuid for INBOX: F7b774e88
2015-07-08T19:58:28+00:00 INFO: [21654] Found 6 folder changes.
2015-07-08T19:58:28+00:00 INFO: [21654] Updating state during foldersync
2015-07-08T19:58:28+00:00 INFO: [21654] Updating state during foldersync
2015-07-08T19:58:28+00:00 INFO: [21654] Updating state during foldersync
2015-07-08T19:58:28+00:00 INFO: [21654] Updating state during foldersync
2015-07-08T19:58:28+00:00 INFO: [21654] Updating state during foldersync
2015-07-08T19:58:28+00:00 INFO: [21654] Updating state during foldersync
2015-07-08T19:58:28+00:00 DEBUG: [21654] O  <FolderHierarchy:FolderSync>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O   <FolderHierarchy:Status>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O    1
2015-07-08T19:58:28+00:00 DEBUG: [21654] O   </FolderHierarchy:Status>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O   <FolderHierarchy:SyncKey>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O    {559d80e4-973c-4195-9d4e-54965850ca18}1
2015-07-08T19:58:28+00:00 DEBUG: [21654] O   </FolderHierarchy:SyncKey>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O   <FolderHierarchy:Changes>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O    <FolderHierarchy:Count>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     6
2015-07-08T19:58:28+00:00 DEBUG: [21654] O    </FolderHierarchy:Count>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O    <FolderHierarchy:Add>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:ServerEntryId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      Tece8c7d2
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:ServerEntryId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:ParentId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      0
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:ParentId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:DisplayName>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      Tasks
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:DisplayName>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:Type>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      7
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:Type>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O    </FolderHierarchy:Add>
2015-07-08T19:58:28+00:00 INFO: [21654] Collection without id found: a:1:{s:8:"serverid";s:7:"@Tasks@";}
2015-07-08T19:58:28+00:00 DEBUG: [21654] O    <FolderHierarchy:Add>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:ServerEntryId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      RI
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:ServerEntryId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:ParentId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      0
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:ParentId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:DisplayName>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      RI
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:DisplayName>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:Type>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      19
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:Type>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O    </FolderHierarchy:Add>
2015-07-08T19:58:28+00:00 INFO: [21654] Collection without id found: a:1:{s:8:"serverid";s:2:"RI";}
2015-07-08T19:58:28+00:00 DEBUG: [21654] O    <FolderHierarchy:Add>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:ServerEntryId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      N540410ba
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:ServerEntryId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:ParentId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      0
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:ParentId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:DisplayName>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      Notes
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:DisplayName>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:Type>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      10
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:Type>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O    </FolderHierarchy:Add>
2015-07-08T19:58:28+00:00 INFO: [21654] Collection without id found: a:1:{s:8:"serverid";s:7:"@Notes@";}
2015-07-08T19:58:28+00:00 DEBUG: [21654] O    <FolderHierarchy:Add>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:ServerEntryId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      F7b774e88
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:ServerEntryId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:ParentId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      0
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:ParentId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:DisplayName>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      Inbox
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:DisplayName>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:Type>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      2
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:Type>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O    </FolderHierarchy:Add>
2015-07-08T19:58:28+00:00 INFO: [21654] Collection without id found: a:1:{s:8:"serverid";s:5:"INBOX";}
2015-07-08T19:58:28+00:00 DEBUG: [21654] O    <FolderHierarchy:Add>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:ServerEntryId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      C7161b4e6
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:ServerEntryId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:ParentId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      0
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:ParentId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:DisplayName>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      Contacts
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:DisplayName>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:Type>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      9
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:Type>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O    </FolderHierarchy:Add>
2015-07-08T19:58:28+00:00 INFO: [21654] Collection without id found: a:1:{s:8:"serverid";s:10:"@Contacts@";}
2015-07-08T19:58:28+00:00 DEBUG: [21654] O    <FolderHierarchy:Add>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:ServerEntryId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      Ac729ec19
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:ServerEntryId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:ParentId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      0
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:ParentId>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:DisplayName>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      Calendar
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:DisplayName>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     <FolderHierarchy:Type>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O      8
2015-07-08T19:58:28+00:00 DEBUG: [21654] O     </FolderHierarchy:Type>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O    </FolderHierarchy:Add>
2015-07-08T19:58:28+00:00 INFO: [21654] Collection without id found: a:1:{s:8:"serverid";s:10:"@Calendar@";}
2015-07-08T19:58:28+00:00 DEBUG: [21654] O   </FolderHierarchy:Changes>
2015-07-08T19:58:28+00:00 DEBUG: [21654] O  </FolderHierarchy:FolderSync>
2015-07-08T19:58:28+00:00 INFO: [21654] Saving state: a:8:{i:0;s:39:"{559d80e4-973c-4195-9d4e-54965850ca18}1";i:1;O:21:"Horde_Db_Value_Binary":1:{s:5:"value";s:737:"a:6:{i:0;a:5:{s:2:"id";s:9:"Tece8c7d2";s:3:"mod";s:5:"Tasks";s:6:"parent";s:1:"0";s:8:"serverid";s:7:"@Tasks@";s:4:"type";i:7;}i:1;a:5:{s:2:"id";s:2:"RI";s:3:"mod";s:2:"RI";s:6:"parent";s:1:"0";s:8:"serverid";s:2:"RI";s:4:"type";i:19;}i:2;a:5:{s:2:"id";s:9:"N540410ba";s:3:"mod";s:5:"Notes";s:6:"parent";s:1:"0";s:8:"serverid";s:7:"@Notes@";s:4:"type";i:10;}i:3;a:5:{s:2:"id";s:9:"F7b774e88";s:3:"mod";s:5:"Inbox";s:6:"parent";s:1:"0";s:8:"serverid";s:5:"INBOX";s:4:"type";i:2;}i:4;a:5:{s:2:"id";s:9:"C7161b4e6";s:3:"mod";s:8:"Contacts";s:6:"parent";s:1:"0";s:8:"serverid";s:10:"@Contacts@";s:4:"type";i:9;}i:5;a:5:{s:2:"id";s:9:"Ac729ec19";s:3:"mod";s:8:"Calendar";s:6:"parent";s:1:"0";s:8:"serverid";s:10:"@Calendar@";s:4:"type";i:8;}}";}i:2;s:19:"ANDROID949141856202";i:3;i:0;i:4;s:10:"foldersync";i:5;s:19:"mmx.test@netdmon.de";i:6;i:0;i:7;i:1436385508;}
2015-07-08T19:58:28+00:00 INFO: [21654] Replacing SYNC_CACHE entry for user mmx.test@netdmon.de and device ANDROID949141856202: a:11:{s:18:"confirmed_synckeys";a:1:{s:39:"{559d806a-b974-4601-9ce0-54a05850ca18}2";b:1;}s:17:"lasthbsyncstarted";b:0;s:17:"lastsyncendnormal";i:1436385387;s:9:"timestamp";s:10:"1436385508";s:4:"wait";b:0;s:10:"hbinterval";b:0;s:7:"folders";a:6:{s:9:"Tece8c7d2";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:"N540410ba";a:2:{s:5:"class";s:5:"Notes";s:8:"serverid";s:7:"@Notes@";}s:9:"F7b774e88";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:5:"INBOX";}s:9:"C7161b4e6";a:2:{s:5:"class";s:8:"Contacts";s:8:"serverid";s:10:"@Contacts@";}s:9:"Ac729ec19";a:2:{s:5:"class";s:8:"Calendar";s:8:"serverid";s:10:"@Calendar@";}}s:9:"hierarchy";s:39:"{559d80e4-973c-4195-9d4e-54965850ca18}1";s:11:"collections";a:0:{}s:13:"pingheartbeat";b:0;s:14:"synckeycounter";a:0:{}}
2015-07-08T19:58:28+00:00 INFO: [21654] Maximum memory usage for ActiveSync request: 36962304 bytes.
2015-07-08T19:58:29+00:00 INFO: [21654] Horde_Core_ActiveSync_Driver::authenticate() attempt for mmx.test@netdmon.de
2015-07-08T19:58:29+00:00 INFO: [21654] SYNC request received for user mmx.test@netdmon.de
2015-07-08T19:58:29+00:00 INFO: [21654] Device entry exists for ANDROID949141856202, updating userAgent and version.
2015-07-08T19:58:29+00:00 INFO: [21654] Request being handled for device: ANDROID949141856202, Supporting protocol version: 14.1, Using Horde_ActiveSync v2.28.5
2015-07-08T19:58:29+00:00 INFO: [21654] GET VARIABLES: Array
(
    [Cmd] => Sync
    [User] => mmx.test@netdmon.de
    [DeviceId] => android949141856202
    [DeviceType] => Android
)

2015-07-08T19:58:29+00:00 INFO: [21654] Handling SYNC command.
2015-07-08T19:58:29+00:00 INFO: [21654] Checking policykey for device: ANDROID949141856202 user: mmx.test@netdmon.de
2015-07-08T19:58:29+00:00 INFO: [21654] Policykey: 0 verified.
2015-07-08T19:58:29+00:00 INFO: [21654] Creating new Horde_ActiveSync_SyncCache.
2015-07-08T19:58:29+00:00 DEBUG: [21654] I  <Synchronize>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I   <Folders>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I    <Folder>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I     <SyncKey>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I       0
2015-07-08T19:58:29+00:00 DEBUG: [21654] I     </SyncKey>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I     <FolderId>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I       C7161b4e6
2015-07-08T19:58:29+00:00 DEBUG: [21654] I     </FolderId>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I     <Supported>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:FirstName />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:LastName />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:MiddleName />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:Suffix />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:CompanyName />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:JobTitle />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:Email1Address />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:Email2Address />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:Email3Address />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:Business2PhoneNumber />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:BusinessPhoneNumber />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS2:MMS />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:BusinessFaxNumber />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS2:CompanyMainPhone />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:HomeFaxNumber />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:HomePhoneNumber />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:Home2PhoneNumber />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:MobilePhoneNumber />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:CarPhoneNumber />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:RadioPhoneNumber />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:PagerNumber />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:AssistnamePhoneNumber />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS2:IMAddress />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS2:IMAddress2 />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS2:IMAddress3 />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:BusinessCity />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:BusinessCountry />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:BusinessPostalCode />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:BusinessState />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:BusinessStreet />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:HomeCity />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:HomeCountry />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:HomePostalCode />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:HomeState />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:HomeStreet />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:OtherCity />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:OtherCountry />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:OtherPostalCode />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:OtherState />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:OtherStreet />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:YomiCompanyName />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:YomiFirstName />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:YomiLastName />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS2:NickName />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:AssistantName />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS2:ManagerName />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:Spouse />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:Department />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:Title />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:OfficeLocation />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS2:CustomerId />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS2:GovernmentId />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS2:AccountName />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:Anniversary />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:Birthday />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:WebPage />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:Picture />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <POOMCONTACTS:FileAs />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I     </Supported>
2015-07-08T19:58:29+00:00 INFO: [21654] Obtaining collection class of Contacts for collection id C7161b4e6
2015-07-08T19:58:29+00:00 INFO: [21654] Device entry exists for ANDROID949141856202, updating userAgent and version.
2015-07-08T19:58:29+00:00 DEBUG: [21654] I    </Folder>
2015-07-08T19:58:29+00:00 INFO: [21654] Collection added to collection handler: collection: @Contacts@, synckey: NONE.
2015-07-08T19:58:29+00:00 DEBUG: [21654] I   </Folders>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I  </Synchronize>
2015-07-08T19:58:29+00:00 INFO: [21654] Loading default OPTIONS for C7161b4e6 collection.
2015-07-08T19:58:29+00:00 INFO: [21654] Clearing all collection synckeys from the cache.
2015-07-08T19:58:29+00:00 INFO: Have syncable collections
2015-07-08T19:58:29+00:00 INFO: [21654] Replacing SYNC_CACHE entry for user mmx.test@netdmon.de and device ANDROID949141856202: a:11:{s:18:"confirmed_synckeys";a:0:{}s:17:"lasthbsyncstarted";b:0;s:17:"lastsyncendnormal";i:1436385387;s:9:"timestamp";s:10:"1436385509";s:4:"wait";b:0;s:10:"hbinterval";b:0;s:7:"folders";a:6:{s:9:"Tece8c7d2";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:"N540410ba";a:2:{s:5:"class";s:5:"Notes";s:8:"serverid";s:7:"@Notes@";}s:9:"F7b774e88";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:5:"INBOX";}s:9:"C7161b4e6";a:2:{s:5:"class";s:8:"Contacts";s:8:"serverid";s:10:"@Contacts@";}s:9:"Ac729ec19";a:2:{s:5:"class";s:8:"Calendar";s:8:"serverid";s:10:"@Calendar@";}}s:9:"hierarchy";s:39:"{559d80e4-973c-4195-9d4e-54965850ca18}1";s:11:"collections";a:1:{s:9:"C7161b4e6";a:7:{s:7:"synckey";s:1:"0";s:5:"class";s:8:"Contacts";s:10:"windowsize";i:100;s:11:"mimesupport";i:0;s:8:"conflict";i:1;s:9:"bodyprefs";a:0:{}s:8:"serverid";s:10:"@Contacts@";}}s:13:"pingheartbeat";b:0;s:14:"synckeycounter";a:0:{}}
2015-07-08T19:58:29+00:00 INFO: [21654] All synckeys confirmed. Continuing with SYNC
2015-07-08T19:58:29+00:00 INFO: [21654] Completed parsing incoming request. Peak memory usage: 18087936.
2015-07-08T19:58:29+00:00 DEBUG: [21654] O  <Synchronize>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O   <Status>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O    1
2015-07-08T19:58:29+00:00 DEBUG: [21654] O   </Status>
2015-07-08T19:58:29+00:00 INFO: [21654] Initializing state for collection: @Contacts@, synckey: 0
2015-07-08T19:58:29+00:00 NOTICE: [21654] Horde_ActiveSync_State_Base::loadState: clearing folder state.
2015-07-08T19:58:29+00:00 INFO: [21654] Resetting device state for device: ANDROID949141856202, user: mmx.test@netdmon.de, and collection: C7161b4e6.
2015-07-08T19:58:29+00:00 INFO: [21654] Creating new Horde_ActiveSync_SyncCache.
2015-07-08T19:58:29+00:00 INFO: [21654] Replacing SYNC_CACHE entry for user mmx.test@netdmon.de and device ANDROID949141856202: a:11:{s:18:"confirmed_synckeys";a:0:{}s:17:"lasthbsyncstarted";b:0;s:17:"lastsyncendnormal";i:1436385387;s:9:"timestamp";s:10:"1436385509";s:4:"wait";b:0;s:10:"hbinterval";b:0;s:7:"folders";a:6:{s:9:"Tece8c7d2";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:"N540410ba";a:2:{s:5:"class";s:5:"Notes";s:8:"serverid";s:7:"@Notes@";}s:9:"F7b774e88";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:5:"INBOX";}s:9:"C7161b4e6";a:2:{s:5:"class";s:8:"Contacts";s:8:"serverid";s:10:"@Contacts@";}s:9:"Ac729ec19";a:2:{s:5:"class";s:8:"Calendar";s:8:"serverid";s:10:"@Calendar@";}}s:9:"hierarchy";s:39:"{559d80e4-973c-4195-9d4e-54965850ca18}1";s:11:"collections";a:1:{s:9:"C7161b4e6";a:7:{s:7:"synckey";s:1:"0";s:5:"class";s:8:"Contacts";s:10:"windowsize";i:100;s:11:"mimesupport";i:0;s:8:"conflict";i:1;s:9:"bodyprefs";a:0:{}s:8:"serverid";s:10:"@Contacts@";}}s:13:"pingheartbeat";b:0;s:14:"synckeycounter";a:0:{}}
2015-07-08T19:58:29+00:00 INFO: [21654] Old SYNCKEY: 0, New SYNCKEY: {559d80e5-2810-46f3-b616-54965850ca18}1
2015-07-08T19:58:29+00:00 DEBUG: [21654] O   <Folders>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O    <Folder>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O     <SyncKey>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O      {559d80e5-2810-46f3-b616-54965850ca18}1
2015-07-08T19:58:29+00:00 DEBUG: [21654] O     </SyncKey>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O     <FolderId>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O      C7161b4e6
2015-07-08T19:58:29+00:00 DEBUG: [21654] O     </FolderId>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O     <Status>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O      1
2015-07-08T19:58:29+00:00 DEBUG: [21654] O     </Status>
2015-07-08T19:58:29+00:00 INFO: [21654] Saving state: a:8:{i:0;s:39:"{559d80e5-2810-46f3-b616-54965850ca18}1";i:1;O:21:"Horde_Db_Value_Binary":1:{s:5:"value";s:118:"C:34:"Horde_ActiveSync_Folder_Collection":71:{{"s":[],"f":"@Contacts@","c":"Contacts","lsd":0,"sd":0,"i":false,"v":1}}";}i:2;s:19:"ANDROID949141856202";i:3;i:0;i:4;s:9:"C7161b4e6";i:5;s:19:"mmx.test@netdmon.de";i:6;i:0;i:7;i:1436385509;}
2015-07-08T19:58:29+00:00 DEBUG: [21654] O    </Folder>
2015-07-08T19:58:29+00:00 INFO: [21654] Collection output peak memory usage: 18350080
2015-07-08T19:58:29+00:00 DEBUG: [21654] O   </Folders>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O  </Synchronize>
2015-07-08T19:58:29+00:00 INFO: [21654] Replacing SYNC_CACHE entry for user mmx.test@netdmon.de and device ANDROID949141856202: a:11:{s:18:"confirmed_synckeys";a:1:{s:39:"{559d80e5-2810-46f3-b616-54965850ca18}1";b:1;}s:17:"lasthbsyncstarted";b:0;s:17:"lastsyncendnormal";i:1436385509;s:9:"timestamp";s:10:"1436385509";s:4:"wait";b:0;s:10:"hbinterval";b:0;s:7:"folders";a:6:{s:9:"Tece8c7d2";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:"N540410ba";a:2:{s:5:"class";s:5:"Notes";s:8:"serverid";s:7:"@Notes@";}s:9:"F7b774e88";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:5:"INBOX";}s:9:"C7161b4e6";a:2:{s:5:"class";s:8:"Contacts";s:8:"serverid";s:10:"@Contacts@";}s:9:"Ac729ec19";a:2:{s:5:"class";s:8:"Calendar";s:8:"serverid";s:10:"@Calendar@";}}s:9:"hierarchy";s:39:"{559d80e4-973c-4195-9d4e-54965850ca18}1";s:11:"collections";a:1:{s:9:"C7161b4e6";a:7:{s:5:"class";s:8:"Contacts";s:10:"windowsize";i:100;s:11:"mimesupport";i:0;s:8:"conflict";i:1;s:9:"bodyprefs";a:0:{}s:8:"serverid";s:10:"@Contacts@";s:11:"lastsynckey";s:39:"{559d80e5-2810-46f3-b616-54965850ca18}1";}}s:13:"pingheartbeat";b:0;s:14:"synckeycounter";a:0:{}}
2015-07-08T19:58:29+00:00 INFO: [21654] Maximum memory usage for ActiveSync request: 18350080 bytes.
2015-07-08T19:58:29+00:00 INFO: [21654] Horde_Core_ActiveSync_Driver::authenticate() attempt for mmx.test@netdmon.de
2015-07-08T19:58:29+00:00 INFO: [21654] SYNC request received for user mmx.test@netdmon.de
2015-07-08T19:58:29+00:00 INFO: [21654] Device entry exists for ANDROID949141856202, updating userAgent and version.
2015-07-08T19:58:29+00:00 INFO: [21654] Request being handled for device: ANDROID949141856202, Supporting protocol version: 14.1, Using Horde_ActiveSync v2.28.5
2015-07-08T19:58:29+00:00 INFO: [21654] GET VARIABLES: Array
(
    [Cmd] => Sync
    [User] => mmx.test@netdmon.de
    [DeviceId] => android949141856202
    [DeviceType] => Android
)

2015-07-08T19:58:29+00:00 INFO: [21654] Handling SYNC command.
2015-07-08T19:58:29+00:00 INFO: [21654] Checking policykey for device: ANDROID949141856202 user: mmx.test@netdmon.de
2015-07-08T19:58:29+00:00 INFO: [21654] Policykey: 0 verified.
2015-07-08T19:58:29+00:00 INFO: [21654] Creating new Horde_ActiveSync_SyncCache.
2015-07-08T19:58:29+00:00 DEBUG: [21654] I  <Synchronize>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I   <Folders>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I    <Folder>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I     <SyncKey>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I       {559d80e5-2810-46f3-b616-54965850ca18}1
2015-07-08T19:58:29+00:00 DEBUG: [21654] I     </SyncKey>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I     <FolderId>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I       C7161b4e6
2015-07-08T19:58:29+00:00 DEBUG: [21654] I     </FolderId>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I     <DeletesAsMoves />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I     <GetChanges />
2015-07-08T19:58:29+00:00 DEBUG: [21654] I     <WindowSize>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I       10
2015-07-08T19:58:29+00:00 DEBUG: [21654] I     </WindowSize>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I     <Options>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      <AirSyncBase:BodyPreference>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I       <AirSyncBase:Type>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I         1
2015-07-08T19:58:29+00:00 DEBUG: [21654] I       </AirSyncBase:Type>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I       <AirSyncBase:TruncationSize>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I         200000
2015-07-08T19:58:29+00:00 DEBUG: [21654] I       </AirSyncBase:TruncationSize>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I      </AirSyncBase:BodyPreference>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I     </Options>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I    </Folder>
2015-07-08T19:58:29+00:00 INFO: [21654] Obtaining collection class of Contacts for collection id C7161b4e6
2015-07-08T19:58:29+00:00 INFO: [21654] Collection added to collection handler: collection: @Contacts@, synckey: {559d80e5-2810-46f3-b616-54965850ca18}1.
2015-07-08T19:58:29+00:00 DEBUG: [21654] I   </Folders>
2015-07-08T19:58:29+00:00 DEBUG: [21654] I  </Synchronize>
2015-07-08T19:58:29+00:00 INFO: [21654] Loading default OPTIONS for C7161b4e6 collection.
2015-07-08T19:58:29+00:00 INFO: [21654] Clearing all collection synckeys from the cache.
2015-07-08T19:58:29+00:00 INFO: Have syncable collections
2015-07-08T19:58:29+00:00 INFO: [21654] Replacing SYNC_CACHE entry for user mmx.test@netdmon.de and device ANDROID949141856202: a:11:{s:18:"confirmed_synckeys";a:0:{}s:17:"lasthbsyncstarted";b:0;s:17:"lastsyncendnormal";i:1436385509;s:9:"timestamp";s:10:"1436385509";s:4:"wait";b:0;s:10:"hbinterval";b:0;s:7:"folders";a:6:{s:9:"Tece8c7d2";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:"N540410ba";a:2:{s:5:"class";s:5:"Notes";s:8:"serverid";s:7:"@Notes@";}s:9:"F7b774e88";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:5:"INBOX";}s:9:"C7161b4e6";a:2:{s:5:"class";s:8:"Contacts";s:8:"serverid";s:10:"@Contacts@";}s:9:"Ac729ec19";a:2:{s:5:"class";s:8:"Calendar";s:8:"serverid";s:10:"@Calendar@";}}s:9:"hierarchy";s:39:"{559d80e4-973c-4195-9d4e-54965850ca18}1";s:11:"collections";a:1:{s:9:"C7161b4e6";a:9:{s:5:"class";s:8:"Contacts";s:10:"windowsize";s:2:"10";s:11:"mimesupport";i:0;s:8:"conflict";i:1;s:9:"bodyprefs";a:1:{i:1;a:2:{s:4:"type";s:1:"1";s:14:"truncationsize";s:6:"200000";}}s:8:"serverid";s:10:"@Contacts@";s:11:"lastsynckey";s:39:"{559d80e5-2810-46f3-b616-54965850ca18}1";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";}}s:13:"pingheartbeat";b:0;s:14:"synckeycounter";a:0:{}}
2015-07-08T19:58:29+00:00 INFO: [21654] All synckeys confirmed. Continuing with SYNC
2015-07-08T19:58:29+00:00 INFO: [21654] Completed parsing incoming request. Peak memory usage: 18087936.
2015-07-08T19:58:29+00:00 DEBUG: [21654] O  <Synchronize>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O   <Status>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O    1
2015-07-08T19:58:29+00:00 DEBUG: [21654] O   </Status>
2015-07-08T19:58:29+00:00 INFO: [21654] Initializing state for collection: @Contacts@, synckey: {559d80e5-2810-46f3-b616-54965850ca18}1
2015-07-08T19:58:29+00:00 INFO: [21654] Loading state for synckey {559d80e5-2810-46f3-b616-54965850ca18}1
2015-07-08T19:58:29+00:00 INFO: [21654] Initializing message diff engine for C7161b4e6 (@Contacts@)
2015-07-08T19:58:29+00:00 INFO: [21654] Horde_Core_ActiveSync_Driver::getSyncStamp(@Contacts@, 0);
2015-07-08T19:58:29+00:00 INFO: [21654] Using SYNCSTAMP 10472 for C7161b4e6.
2015-07-08T19:58:29+00:00 INFO: [21654] Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 0, 10472, 0, 0, , 100, )
2015-07-08T19:58:29+00:00 INFO: [21654] Initial sync, only sending UID.
2015-07-08T19:58:29+00:00 INFO: [21654] Found 2 message changes in C7161b4e6.
2015-07-08T19:58:29+00:00 INFO: [21654] No client changes present, returning all messages.
2015-07-08T19:58:29+00:00 INFO: [21654] Old SYNCKEY: {559d80e5-2810-46f3-b616-54965850ca18}1, New SYNCKEY: {559d80e5-2810-46f3-b616-54965850ca18}2
2015-07-08T19:58:29+00:00 DEBUG: [21654] O   <Folders>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O    <Folder>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O     <SyncKey>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O      {559d80e5-2810-46f3-b616-54965850ca18}2
2015-07-08T19:58:29+00:00 DEBUG: [21654] O     </SyncKey>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O     <FolderId>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O      C7161b4e6
2015-07-08T19:58:29+00:00 DEBUG: [21654] O     </FolderId>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O     <Status>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O      1
2015-07-08T19:58:29+00:00 DEBUG: [21654] O     </Status>
2015-07-08T19:58:29+00:00 INFO: [21654] Horde_Core_ActiveSync_Driver::getMessage(@Contacts@, 20150708195515.9FmAfMOzkQaPt2XQPTUw7WW@horde.netdmon.de)
2015-07-08T19:58:29+00:00 DEBUG: [21654] O     <Commands>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O      <Add>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O       <ServerEntryId>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O        [55 bytes of content]
2015-07-08T19:58:29+00:00 DEBUG: [21654] O       </ServerEntryId>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O       <Data>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O        <POOMCONTACTS:FileAs>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O         a a
2015-07-08T19:58:29+00:00 DEBUG: [21654] O        </POOMCONTACTS:FileAs>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O        <POOMCONTACTS:FirstName>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O         a
2015-07-08T19:58:29+00:00 DEBUG: [21654] O        </POOMCONTACTS:FirstName>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O        <POOMCONTACTS:LastName>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O         a
2015-07-08T19:58:29+00:00 DEBUG: [21654] O        </POOMCONTACTS:LastName>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O        <AirSyncBase:Body>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O         <AirSyncBase:Type>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O          1
2015-07-08T19:58:29+00:00 DEBUG: [21654] O         </AirSyncBase:Type>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O         <AirSyncBase:EstimatedDataSize>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O          0
2015-07-08T19:58:29+00:00 DEBUG: [21654] O         </AirSyncBase:EstimatedDataSize>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O        </AirSyncBase:Body>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O       </Data>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O      </Add>
2015-07-08T19:58:29+00:00 INFO: [21654] Updating state during change
2015-07-08T19:58:29+00:00 INFO: [21654] Peak memory usage after message: 24903680
2015-07-08T19:58:29+00:00 INFO: [21654] Horde_Core_ActiveSync_Driver::getMessage(@Contacts@, 20150708195523.TW5AOw1uaYE7eMzZcSwxeSB@horde.netdmon.de)
2015-07-08T19:58:29+00:00 DEBUG: [21654] O      <Add>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O       <ServerEntryId>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O        [55 bytes of content]
2015-07-08T19:58:29+00:00 DEBUG: [21654] O       </ServerEntryId>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O       <Data>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O        <POOMCONTACTS:FileAs>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O         b b
2015-07-08T19:58:29+00:00 DEBUG: [21654] O        </POOMCONTACTS:FileAs>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O        <POOMCONTACTS:FirstName>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O         b
2015-07-08T19:58:29+00:00 DEBUG: [21654] O        </POOMCONTACTS:FirstName>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O        <POOMCONTACTS:LastName>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O         b
2015-07-08T19:58:29+00:00 DEBUG: [21654] O        </POOMCONTACTS:LastName>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O        <AirSyncBase:Body>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O         <AirSyncBase:Type>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O          1
2015-07-08T19:58:29+00:00 DEBUG: [21654] O         </AirSyncBase:Type>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O         <AirSyncBase:EstimatedDataSize>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O          0
2015-07-08T19:58:29+00:00 DEBUG: [21654] O         </AirSyncBase:EstimatedDataSize>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O        </AirSyncBase:Body>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O       </Data>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O      </Add>
2015-07-08T19:58:29+00:00 INFO: [21654] Updating state during change
2015-07-08T19:58:29+00:00 INFO: [21654] Peak memory usage after message: 25165824
2015-07-08T19:58:29+00:00 DEBUG: [21654] O     </Commands>
2015-07-08T19:58:29+00:00 INFO: [21654] Saving state: a:8:{i:0;s:39:"{559d80e5-2810-46f3-b616-54965850ca18}2";i:1;O:21:"Horde_Db_Value_Binary":1:{s:5:"value";s:117:"C:34:"Horde_ActiveSync_Folder_Collection":70:{{"s":[],"f":"@Contacts@","c":"Contacts","lsd":0,"sd":0,"i":true,"v":1}}";}i:2;s:19:"ANDROID949141856202";i:3;i:10472;i:4;s:9:"C7161b4e6";i:5;s:19:"mmx.test@netdmon.de";i:6;i:0;i:7;i:1436385509;}
2015-07-08T19:58:29+00:00 DEBUG: [21654] O    </Folder>
2015-07-08T19:58:29+00:00 INFO: [21654] Collection output peak memory usage: 25165824
2015-07-08T19:58:29+00:00 DEBUG: [21654] O   </Folders>
2015-07-08T19:58:29+00:00 DEBUG: [21654] O  </Synchronize>
2015-07-08T19:58:29+00:00 INFO: [21654] Replacing SYNC_CACHE entry for user mmx.test@netdmon.de and device ANDROID949141856202: a:11:{s:18:"confirmed_synckeys";a:1:{s:39:"{559d80e5-2810-46f3-b616-54965850ca18}2";b:1;}s:17:"lasthbsyncstarted";b:0;s:17:"lastsyncendnormal";i:1436385509;s:9:"timestamp";s:10:"1436385509";s:4:"wait";b:0;s:10:"hbinterval";b:0;s:7:"folders";a:6:{s:9:"Tece8c7d2";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:"N540410ba";a:2:{s:5:"class";s:5:"Notes";s:8:"serverid";s:7:"@Notes@";}s:9:"F7b774e88";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:5:"INBOX";}s:9:"C7161b4e6";a:2:{s:5:"class";s:8:"Contacts";s:8:"serverid";s:10:"@Contacts@";}s:9:"Ac729ec19";a:2:{s:5:"class";s:8:"Calendar";s:8:"serverid";s:10:"@Calendar@";}}s:9:"hierarchy";s:39:"{559d80e4-973c-4195-9d4e-54965850ca18}1";s:11:"collections";a:1:{s:9:"C7161b4e6";a:9:{s:5:"class";s:8:"Contacts";s:10:"windowsize";s:2:"10";s:11:"mimesupport";i:0;s:8:"conflict";i:1;s:9:"bodyprefs";a:1:{i:1;a:2:{s:4:"type";s:1:"1";s:14:"truncationsize";s:6:"200000";}}s:8:"serverid";s:10:"@Contacts@";s:11:"lastsynckey";s:39:"{559d80e5-2810-46f3-b616-54965850ca18}2";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";}}s:13:"pingheartbeat";b:0;s:14:"synckeycounter";a:0:{}}
2015-07-08T19:58:29+00:00 INFO: [21654] Maximum memory usage for ActiveSync request: 25165824 bytes.
2015-07-08T19:58:35+00:00 INFO: [21654] Horde_Core_ActiveSync_Driver::authenticate() attempt for mmx.test@netdmon.de
2015-07-08T19:58:35+00:00 INFO: [21654] FOLDERSYNC request received for user mmx.test@netdmon.de
2015-07-08T19:58:35+00:00 INFO: [21654] Device entry exists for ANDROID949141856202, updating userAgent and version.
2015-07-08T19:58:35+00:00 INFO: [21654] Request being handled for device: ANDROID949141856202, Supporting protocol version: 14.1, Using Horde_ActiveSync v2.28.5
2015-07-08T19:58:35+00:00 INFO: [21654] GET VARIABLES: Array
(
    [Cmd] => FolderSync
    [User] => mmx.test@netdmon.de
    [DeviceId] => android949141856202
    [DeviceType] => Android
)

2015-07-08T19:58:35+00:00 INFO: [21654] Handling FOLDERSYNC command.
2015-07-08T19:58:35+00:00 INFO: [21654] Checking policykey for device: ANDROID949141856202 user: mmx.test@netdmon.de
2015-07-08T19:58:35+00:00 INFO: [21654] Policykey: 0 verified.
2015-07-08T19:58:35+00:00 DEBUG: [21654] I  <FolderHierarchy:FolderSync>
2015-07-08T19:58:35+00:00 DEBUG: [21654] I   <FolderHierarchy:SyncKey>
2015-07-08T19:58:35+00:00 DEBUG: [21654] I     {559d80e4-973c-4195-9d4e-54965850ca18}1
2015-07-08T19:58:35+00:00 DEBUG: [21654] I   </FolderHierarchy:SyncKey>
2015-07-08T19:58:35+00:00 INFO: [21654] Creating new Horde_ActiveSync_SyncCache.
2015-07-08T19:58:35+00:00 INFO: [21654] Loading state for synckey {559d80e4-973c-4195-9d4e-54965850ca18}1
2015-07-08T19:58:35+00:00 INFO: [21654] Loading FOLDERSYNC state containing 6 folders.
2015-07-08T19:58:35+00:00 DEBUG: [21654] I  </FolderHierarchy:FolderSync>
2015-07-08T19:58:35+00:00 INFO: [21654] Initializing folder diff engine
2015-07-08T19:58:35+00:00 INFO: [21654] Horde_Core_ActiveSync_Driver::getFolderList()
2015-07-08T19:58:35+00:00 INFO: [21654] Polling Horde_Core_ActiveSync_Driver::_getMailFolders()
2015-07-08T19:58:35+00:00 INFO: [21654] No folder changes found.
2015-07-08T19:58:35+00:00 DEBUG: [21654] O  <FolderHierarchy:FolderSync>
2015-07-08T19:58:35+00:00 DEBUG: [21654] O   <FolderHierarchy:Status>
2015-07-08T19:58:35+00:00 DEBUG: [21654] O    1
2015-07-08T19:58:35+00:00 DEBUG: [21654] O   </FolderHierarchy:Status>
2015-07-08T19:58:35+00:00 DEBUG: [21654] O   <FolderHierarchy:SyncKey>
2015-07-08T19:58:35+00:00 DEBUG: [21654] O    {559d80e4-973c-4195-9d4e-54965850ca18}1
2015-07-08T19:58:35+00:00 DEBUG: [21654] O   </FolderHierarchy:SyncKey>
2015-07-08T19:58:35+00:00 DEBUG: [21654] O   <FolderHierarchy:Changes>
2015-07-08T19:58:35+00:00 DEBUG: [21654] O    <FolderHierarchy:Count>
2015-07-08T19:58:35+00:00 DEBUG: [21654] O     0
2015-07-08T19:58:35+00:00 DEBUG: [21654] O    </FolderHierarchy:Count>
2015-07-08T19:58:35+00:00 DEBUG: [21654] O   </FolderHierarchy:Changes>
2015-07-08T19:58:35+00:00 DEBUG: [21654] O  </FolderHierarchy:FolderSync>
2015-07-08T19:58:35+00:00 INFO: [21654] Replacing SYNC_CACHE entry for user mmx.test@netdmon.de and device ANDROID949141856202: a:11:{s:18:"confirmed_synckeys";a:1:{s:39:"{559d80e5-2810-46f3-b616-54965850ca18}2";b:1;}s:17:"lasthbsyncstarted";b:0;s:17:"lastsyncendnormal";i:1436385509;s:9:"timestamp";s:10:"1436385515";s:4:"wait";b:0;s:10:"hbinterval";b:0;s:7:"folders";a:6:{s:9:"Tece8c7d2";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:"N540410ba";a:2:{s:5:"class";s:5:"Notes";s:8:"serverid";s:7:"@Notes@";}s:9:"F7b774e88";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:5:"INBOX";}s:9:"C7161b4e6";a:2:{s:5:"class";s:8:"Contacts";s:8:"serverid";s:10:"@Contacts@";}s:9:"Ac729ec19";a:2:{s:5:"class";s:8:"Calendar";s:8:"serverid";s:10:"@Calendar@";}}s:9:"hierarchy";s:39:"{559d80e4-973c-4195-9d4e-54965850ca18}1";s:11:"collections";a:1:{s:9:"C7161b4e6";a:9:{s:5:"class";s:8:"Contacts";s:10:"windowsize";s:2:"10";s:11:"mimesupport";i:0;s:8:"conflict";i:1;s:9:"bodyprefs";a:1:{i:1;a:2:{s:4:"type";s:1:"1";s:14:"truncationsize";s:6:"200000";}}s:8:"serverid";s:10:"@Contacts@";s:11:"lastsynckey";s:39:"{559d80e5-2810-46f3-b616-54965850ca18}2";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";}}s:13:"pingheartbeat";b:0;s:14:"synckeycounter";a:0:{}}
2015-07-08T19:58:35+00:00 INFO: [21654] Maximum memory usage for ActiveSync request: 36962304 bytes.