[horde] Duplicate entry in horde_activesync_state

lg at wb7.eu lg at wb7.eu
Sun Aug 20 18:10:07 UTC 2017 

19. August 2017 18:29, "Michael J Rubinsky" <mrubinsk at horde.org> schrieb:

> Quoting Michael J Rubinsky <mrubinsk at horde.org>:
> 
>> Quoting lg at wb7.eu:
>> 
>>> 16. August 2017 13:28, "Michael J Rubinsky" <mrubinsk at horde.org> schrieb:
>> 
>> Quoting lg at wb7.eu:
>> 
>> Hello everyone,
>> 
>> I am using the current version (5.2.21) of Horde on PHP 7.1, Debian based.
>> 
>> Since today, syncing tasks over ActiveSync is suddenly impossible -
>> neither Outlook 2016 nor Task&Notes on Android are able to sync.
>> When trying to sync, the log file fills with entries like this:
>> 
>> 2017-08-16T12:47:38+02:00 ERR: HORDE SQL QUERY FAILED:
>> SQLSTATE[23000]: Integrity constraint violation: 1062 Duplicate
>> entry '{59941a2f-314c-47a8-b8a1-7d0dbc442056}4' for key 'PRIMARY'
>> INSERT INTO `horde_activesync_state` (`sync_key`, `sync_data`,
>> `sync_devid`, `sync_mod`, `sync_folderid`, `sync_user`,
>> `sync_pending`, `sync_timestamp`) VALUES (?, :binary0, ?,
>> ?, ?, ?, ?,
>> ?) [pid 18045 on line 282 of
>> "/var/www/horde/htdocs/pear/php/Horde/Db/Adapter/Pdo/Base.php"]
>> 
>> Is there any idea how to fix this? I've tried removing the entries
>> manually from the database, but they reappear (with a new UUID). I
>> don't know why this happens since today.
>> 
>> This is normally an indication that the client either did not receive
>> the server's response, did not understand it, or the server response
>> was broken in some way. In *most* cases, this is a normal error and
>> the client tries again with the same synckey (which indicates the sync
>> point the client is at).
>> 
>> Enable ActiveSync debugging in the Horde configuration and post the
>> relevant log entries from that log for further assistance.
>> 
>> Thanks in advance!
>> 
>> Regards,
>> Lucas
>> --
>> Horde mailing list
>> Frequently Asked Questions: http://horde.org/faq
>> To unsubscribe, mail: horde-unsubscribe at lists.horde.org
>> 
>> --
>> mike
>> The Horde Project
>> http://www.horde.org
>> https://www.facebook.com/hordeproject
>> https://www.twitter.com/hordeproject
>> 
>> --
>> Horde mailing list
>> Frequently Asked Questions: http://horde.org/faq
>> To unsubscribe, mail: horde-unsubscribe at lists.horde.org
>>> Thank you for your fast response. Here is an active sync log:
>>> [18090][2017-08-16T17:13:48+02:00] INFO:
>>> ----------Horde_Core_ActiveSync_Driver::authenticate() attempt for
>>> lucas at domain.tld----------
>>> [18090][2017-08-16T17:13:48+02:00] INFO: ----------SYNC request
>>> received for user lucas at domain.tld
>>> [18090][2017-08-16T17:13:48+02:00] >>>: STATE: Device entry exists
>>> for 4E6F746573444546414345, updating userAgent, version, and
>>> supported.
>>> [18090][2017-08-16T17:13:48+02:00] INFO: ----------Request being
>>> handled for device: 4E6F746573444546414345, Supporting protocol
>>> version: 14.0, Using Horde_ActiveSync v2.38.7
>>> [18090][2017-08-16T17:13:48+02:00] >>>: GET VARIABLES: Array
>>> (
>>> [ProtVer] => 14.0
>>> [Cmd] => Sync
>>> [Locale] => 1033
>>> [DeviceId] => 4e6f746573444546414345
>>> [PolicyKey] => 0
>>> [DeviceType] => TasksNotes
>>> )
>>> 
>>> [18090][2017-08-16T17:13:48+02:00] >>>: Handling SYNC command.
>>> [18090][2017-08-16T17:13:48+02:00] >>>: Checking policykey for
>>> device: 4E6F746573444546414345 user: lucas at domain.tld
>>> [18090][2017-08-16T17:13:48+02:00] INFO: Policykey: 0 verified.
>>> [18090][2017-08-16T17:13:48+02:00] >>>: Creating new
>>> Horde_ActiveSync_SyncCache.
>>> [18090][2017-08-16T17:13:48+02:00] I: <Synchronize>
>>> [18090][2017-08-16T17:13:48+02:00] I: <Folders>
>>> [18090][2017-08-16T17:13:48+02:00] I: <Folder>
>>> [18090][2017-08-16T17:13:48+02:00] I: <SyncKey>
>>> [18090][2017-08-16T17:13:48+02:00] I:
>>> {598d3823-a458-4efe-a027-4985bc442056}27
>>> [18090][2017-08-16T17:13:48+02:00] I: </SyncKey>
>>> [18090][2017-08-16T17:13:48+02:00] I: <FolderId>
>>> [18090][2017-08-16T17:13:48+02:00] I: T1432e34a
>>> [18090][2017-08-16T17:13:48+02:00] I: </FolderId>
>>> [18090][2017-08-16T17:13:48+02:00] I: <DeletesAsMoves />
>>> [18090][2017-08-16T17:13:48+02:00] I: <GetChanges />
>>> [18090][2017-08-16T17:13:48+02:00] I: <Options>
>>> [18090][2017-08-16T17:13:48+02:00] I: <FilterType>
>>> [18090][2017-08-16T17:13:48+02:00] I: 0
>>> [18090][2017-08-16T17:13:48+02:00] I: </FilterType>
>>> [18090][2017-08-16T17:13:48+02:00] I: <Conflict>
>>> [18090][2017-08-16T17:13:48+02:00] I: 1
>>> [18090][2017-08-16T17:13:48+02:00] I: </Conflict>
>>> [18090][2017-08-16T17:13:48+02:00] I: <AirSyncBase:BodyPreference>
>>> [18090][2017-08-16T17:13:48+02:00] I: <AirSyncBase:Type>
>>> [18090][2017-08-16T17:13:48+02:00] I: 1
>>> [18090][2017-08-16T17:13:48+02:00] I: </AirSyncBase:Type>
>>> [18090][2017-08-16T17:13:48+02:00] I: <AirSyncBase:TruncationSize>
>>> [18090][2017-08-16T17:13:48+02:00] I: 1048576
>>> [18090][2017-08-16T17:13:48+02:00] I: </AirSyncBase:TruncationSize>
>>> [18090][2017-08-16T17:13:48+02:00] I: </AirSyncBase:BodyPreference>
>>> [18090][2017-08-16T17:13:48+02:00] I: </Options>
>>> [18090][2017-08-16T17:13:48+02:00] I: </Folder>
>>> [18090][2017-08-16T17:13:48+02:00] >>>: COLLECTIONS: Obtaining
>>> collection class of Tasks for collection id T1432e34a
>>> [18090][2017-08-16T17:13:48+02:00] >>>: COLLECTIONS: Collection
>>> added to collection handler: collection: @Tasks@, synckey:
>>> {598d3823-a458-4efe-a027-4985bc442056}27.
>>> [18090][2017-08-16T17:13:48+02:00] I: <Folder>
>>> [18090][2017-08-16T17:13:48+02:00] I: <SyncKey>
>>> [18090][2017-08-16T17:13:48+02:00] I:
>>> {598d3841-f734-43c4-ab1d-4985bc442056}26
>>> [18090][2017-08-16T17:13:48+02:00] I: </SyncKey>
>>> [18090][2017-08-16T17:13:48+02:00] I: <FolderId>
>>> [18090][2017-08-16T17:13:48+02:00] I: F206e2a41
>>> [18090][2017-08-16T17:13:48+02:00] I: </FolderId>
>>> [18090][2017-08-16T17:13:48+02:00] I: <DeletesAsMoves />
>>> [18090][2017-08-16T17:13:48+02:00] I: <GetChanges />
>>> [18090][2017-08-16T17:13:48+02:00] I: <Options>
>>> [18090][2017-08-16T17:13:48+02:00] I: <FilterType>
>>> [18090][2017-08-16T17:13:48+02:00] I: 5
>>> [18090][2017-08-16T17:13:48+02:00] I: </FilterType>
>>> [18090][2017-08-16T17:13:48+02:00] I: <Conflict>
>>> [18090][2017-08-16T17:13:48+02:00] I: 1
>>> [18090][2017-08-16T17:13:48+02:00] I: </Conflict>
>>> [18090][2017-08-16T17:13:48+02:00] I: <AirSyncBase:BodyPreference>
>>> [18090][2017-08-16T17:13:48+02:00] I: <AirSyncBase:Type>
>>> [18090][2017-08-16T17:13:48+02:00] I: 1
>>> [18090][2017-08-16T17:13:48+02:00] I: </AirSyncBase:Type>
>>> [18090][2017-08-16T17:13:48+02:00] I: <AirSyncBase:TruncationSize>
>>> [18090][2017-08-16T17:13:48+02:00] I: 0
>>> [18090][2017-08-16T17:13:48+02:00] I: </AirSyncBase:TruncationSize>
>>> [18090][2017-08-16T17:13:48+02:00] I: </AirSyncBase:BodyPreference>
>>> [18090][2017-08-16T17:13:48+02:00] I: </Options>
>>> [18090][2017-08-16T17:13:48+02:00] I: </Folder>
>>> [18090][2017-08-16T17:13:48+02:00] >>>: COLLECTIONS: Obtaining
>>> collection class of Email for collection id F206e2a41
>>> [18090][2017-08-16T17:13:48+02:00] >>>: COLLECTIONS: Collection
>>> added to collection handler: collection: INBOX, synckey:
>>> {598d3841-f734-43c4-ab1d-4985bc442056}26.
>>> [18090][2017-08-16T17:13:48+02:00] I: </Folders>
>>> [18090][2017-08-16T17:13:48+02:00] I: <WindowSize>
>>> [18090][2017-08-16T17:13:48+02:00] I: 32
>>> [18090][2017-08-16T17:13:48+02:00] I: </WindowSize>
>>> [18090][2017-08-16T17:13:48+02:00] I: </Synchronize>
>>> [18090][2017-08-16T17:13:48+02:00] >>>: COLLECTIONS: Loading
>>> default OPTIONS for T1432e34a collection.
>>> [18090][2017-08-16T17:13:48+02:00] >>>: COLLECTIONS: Loading
>>> default OPTIONS for F206e2a41 collection.
>>> [18090][2017-08-16T17:13:48+02:00] >>>: Clearing all collection
>>> synckeys from the cache.
>>> [18090][2017-08-16T17:13:48+02:00] >>>: COLLECTIONS: Have syncable
>>> collections!
>>> [18090][2017-08-16T17:13:48+02:00] >>>: STATE: Replacing SYNC_CACHE
>>> entry for user lucas at domain.tld and device 4E6F746573444546414345:
>>> a:11:{s:18:"confirmed_synckeys";a:0:{}s:17:"lasthbsyncstarted";b:0;s:17:"lastsyncendnormal";i:150289
>>> 426;s:9:"timestamp";s:10:"1502896428";s:4:"wait";b:0;s:10:"hbinterval";b:0;s:7:"folders";a:16:{s:9:"
>>> 1432e34a";a:3:{s:5:"class";s:5:"Tasks";s:8:"serverid";s:7:"@Tasks@";s:4:"type";i:7;}s:2:"RI";a:3:{s:
>>> :"class";s:5:"Email";s:8:"serverid";s:2:"RI";s:4:"type";i:19;}s:6:"OUTBOX";a:3:{s:5:"class";s:5:"Ema
>>> l";s:8:"serverid";s:6:"OUTBOX";s:4:"type";i:6;}s:9:"Fdbc4791f";a:3:{s:5:"class";s:5:"Email";s:8:"ser
>>> erid";s:10:"Mailfilter";s:4:"type";i:12;}s:9:"Fb2d19f4e";a:3:{s:5:"class";s:5:"Email";s:8:"serverid"
>>> s:22:"shared/service at lmgg.de";s:4:"type";i:12;}s:9:"Fa5eeb068";a:3:{s:5:"class";s:5:"Email";s:8:"ser
>>> erid";s:19:"shared/info at lmgg.de";s:4:"type";i:12;}s:9:"Fa5c70c40";a:3:{s:5:"class";s:5:"Email";s:8:"
>>> erverid";s:4:"Spam";s:4:"type";i:12;}s:9:"F8e9419f1";a:
>>> 3:{s:5:"class";s:5:"Email";s:8:"serverid";s:8:"Cronjobs";s:4:"type";i:12;}s:9:"F63bb19b2";a:3:{s:5:"
>>> lass";s:5:"Email";s:8:"serverid";s:25:"shared/hostmaster at lmgg.de";s:4:"type";i:12;}s:9:"F5cec613d";a
>>> 3:{s:5:"class";s:5:"Email";s:8:"serverid";s:5:"Trash";s:4:"type";i:12;}s:9:"F5269a6fa";a:3:{s:5:"cla
>>> s";s:5:"Email";s:8:"serverid";s:6:"Drafts";s:4:"type";i:3;}s:9:"F24089941";a:3:{s:5:"class";s:5:"Ema
>>> l";s:8:"serverid";s:4:"Sent";s:4:"type";i:5;}s:9:"F206e2a41";a:3:{s:5:"class";s:5:"Email";s:8:"serve
>>> id";s:5:"INBOX";s:4:"type";i:2;}s:9:"F0a21fe11";a:3:{s:5:"class";s:5:"Email";s:8:"serverid";s:23:"sh
>>> red/security at lmgg.de";s:4:"type";i:12;}s:9:"Cd64fc83c";a:3:{s:5:"class";s:8:"Contacts";s:8:"serverid
>>> ;s:10:"@Contacts@";s:4:"type";i:9;}s:9:"A11d65f84";a:3:{s:5:"class";s:8:"Calendar";s:8:"serverid";s:
>>> 2:"Calendar:fRxzKnAs_boufjYeAsZDCnX";s:4:"type";i:8;}}s:9:"hierarchy";s:39:"{598d3820-59e0-48f7-a957
>>> 49b5bc442056}1";s:11:"collections";a:2:{s:9:"T1432e34a";a:9:{s:5:"class";s:5:"Tasks";s
>>> :
>>> 1
>>> 0:"windowsize";i:100;s:11:"mimesupport";i:0;s:8:"conflict";s:1:"1";s:9:"bodyprefs";a:1:{i:1;a:2:{s:4
>>> "type";s:1:"1";s:14:"truncationsize";s:7:"1048576";}}s:8:"serverid";s:7:"@Tasks@";s:11:"lastsynckey"
>>> s:40:"{598d3823-a458-4efe-a027-4985bc442056}27";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";
>>> s:9:"F206e2a41";a:9:{s:5:"class";s:5:"Email";s:10:"windowsize";i:100;s:11:"mimesupport";i:0;s:8:"con
>>> lict";s:1:"1";s:9:"bodyprefs";a:1:{i:1;a:2:{s:4:"type";s:1:"1";s:14:"truncationsize";s:1:"0";}}s:8:"
>>> erverid";s:5:"INBOX";s:11:"lastsynckey";s:40:"{598d3841-f734-43c4-ab1d-4985bc442056}26";s:14:"delete
>>> asmoves";b:1;s:10:"filtertype";s:1:"5";}}s:13:"pingheartbeat";b:0;s:14:"synckeycounter";a:0:{}}
>>> [18090][2017-08-16T17:13:48+02:00] >>>: All synckeys confirmed.
>>> Continuing with SYNC
>>> [18090][2017-08-16T17:13:48+02:00] INFO: Completed parsing incoming
>>> request. Peak memory usage: 2097152.
>>> [18090][2017-08-16T17:13:48+02:00] O: <Synchronize>
>>> [18090][2017-08-16T17:13:48+02:00] O: <Status>
>>> [18090][2017-08-16T17:13:48+02:00] O: 1
>>> [18090][2017-08-16T17:13:48+02:00] O: </Status>
>>> [18090][2017-08-16T17:13:48+02:00] INFO: COLLECTIONS: Initializing
>>> state for collection: @Tasks@, synckey:
>>> {598d3823-a458-4efe-a027-4985bc442056}27
>>> [18090][2017-08-16T17:13:48+02:00] >>>: STATE: Loading state for
>>> synckey {598d3823-a458-4efe-a027-4985bc442056}27
>>> [18090][2017-08-16T17:13:48+02:00] >>>: STATE: Initializing message
>>> diff engine for T1432e34a (@Tasks@)
>>> [18090][2017-08-16T17:13:48+02:00] >>>:
>>> Horde_Core_ActiveSync_Driver::getSyncStamp(@Tasks@, 1763);
>>> [18090][2017-08-16T17:13:48+02:00] >>>: STATE: Using SYNCSTAMP 1770
>>> for T1432e34a.
>>> [18090][2017-08-16T17:13:48+02:00] >>>:
>>> Horde_Core_ActiveSync_Driver::getServerChanges(@Tasks@, 1763, 1770,
>>> 0, 0, 1, 100, )
>>> [18090][2017-08-16T17:13:48+02:00] >>>: Fetching changes for tasks
>>> using MODSEQ.
>>> [18090][2017-08-16T17:13:48+02:00] >>>: STATE: Found 2 message
>>> changes in T1432e34a.
>>> [18090][2017-08-16T17:13:48+02:00] >>>: STATE: No client changes,
>>> returning all messages.
>>> [18090][2017-08-16T17:13:48+02:00] >>>: Old SYNCKEY:
>>> {598d3823-a458-4efe-a027-4985bc442056}27, New SYNCKEY:
>>> {598d3823-a458-4efe-a027-4985bc442056}28
>>> [18090][2017-08-16T17:13:48+02:00] O: <Folders>
>>> [18090][2017-08-16T17:13:48+02:00] O: <Folder>
>>> [18090][2017-08-16T17:13:48+02:00] O: <SyncKey>
>>> [18090][2017-08-16T17:13:48+02:00] O:
>>> {598d3823-a458-4efe-a027-4985bc442056}28
>>> [18090][2017-08-16T17:13:48+02:00] O: </SyncKey>
>>> [18090][2017-08-16T17:13:48+02:00] O: <FolderId>
>>> [18090][2017-08-16T17:13:48+02:00] O: T1432e34a
>>> [18090][2017-08-16T17:13:48+02:00] O: </FolderId>
>>> [18090][2017-08-16T17:13:48+02:00] O: <Status>
>>> [18090][2017-08-16T17:13:48+02:00] O: 1
>>> [18090][2017-08-16T17:13:48+02:00] O: </Status>
>>> [18090][2017-08-16T17:13:48+02:00] >>>:
>>> Horde_Core_ActiveSync_Driver::getMessage(@Tasks@,
>>> 20170815234106.fJx00v44KYMozdhuc_j_Ioc at horde.domain.tld)
>>> [18090][2017-08-16T17:13:48+02:00] NOTICE: Message gone or error
>>> reading message from server: Tasks not found
>>> [18090][2017-08-16T17:13:48+02:00] >>>: STATE: Updating state during change
>>> [18090][2017-08-16T17:13:48+02:00] >>>: Peak memory usage after
>>> message: 2097152
>>> [18090][2017-08-16T17:13:48+02:00] O: <Commands>
>>> [18090][2017-08-16T17:13:48+02:00] O: <Remove>
>>> [18090][2017-08-16T17:13:48+02:00] O: <ServerEntryId>
>>> [18090][2017-08-16T17:13:48+02:00] O:
>>> 20170815234106.fJx00v44KYMozdhuc_j_Ioc at horde.domain.tld
>>> [18090][2017-08-16T17:13:48+02:00] O: </ServerEntryId>
>>> [18090][2017-08-16T17:13:48+02:00] O: </Remove>
>>> [18090][2017-08-16T17:13:48+02:00] >>>: STATE: Updating state during delete
>>> [18090][2017-08-16T17:13:48+02:00] >>>: Peak memory usage after
>>> message: 2097152
>>> [18090][2017-08-16T17:13:48+02:00] O: </Commands>
>>> [18090][2017-08-16T17:13:48+02:00] >>>: STATE: Saving state:
>>> a:8:{i:0;s:40:"{598d3823-a458-4efe-a027-4985bc442056}28";i:1;O:21:"Horde_Db_Value_Binary":2:{s:9:"*_
>>> alue";s:111:"C:34:"Horde_ActiveSync_Folder_Collection":64:{{"s":[],"f":"@Tasks@","c":"Tasks","lsd":0
>>> "sd":0,"i":true,"v":1}}";s:10:"*_stream";N;}i:2;s:22:"4E6F746573444546414345";i:3;i:1770;i:4;s:9:"T1
>>> 32e34a";i:5;s:19:"lucas at domain.tld";i:6;i:0;i:7;i:1502896428;}
>>> [18090][2017-08-16T17:13:48+02:00] NOTICE: STATE: Error saving
>>> state, checking if this is due to previous synckey
>>> {598d3823-a458-4efe-a027-4985bc442056}28 not being accepted by
>>> client.
>>> [18090][2017-08-16T17:13:48+02:00] O: </Folder>
>>> [18090][2017-08-16T17:13:48+02:00] >>>: Collection output peak
>>> memory usage: 2097152
>>> [18090][2017-08-16T17:13:48+02:00] INFO: COLLECTIONS: Initializing
>>> state for collection: INBOX, synckey:
>>> {598d3841-f734-43c4-ab1d-4985bc442056}26
>>> [18090][2017-08-16T17:13:48+02:00] >>>: STATE: Loading state for
>>> synckey {598d3841-f734-43c4-ab1d-4985bc442056}26
>>> [18090][2017-08-16T17:13:48+02:00] >>>: STATE: Initializing message
>>> diff engine for F206e2a41 (INBOX)
>>> [18090][2017-08-16T17:13:48+02:00] >>>:
>>> Horde_Core_ActiveSync_Driver::getSyncStamp(INBOX, 1502868748);
>>> [18090][2017-08-16T17:13:48+02:00] >>>: STATE: Using SYNCSTAMP
>>> 1502896428 for F206e2a41.
>>> [18090][2017-08-16T17:13:48+02:00] >>>:
>>> Horde_Core_ActiveSync_Driver::getServerChanges(INBOX, 1502868748,
>>> 1502896428, 1500477228, 0, 1, 100, )
>>> [18090][2017-08-16T17:13:48+02:00] >>>: INBOX IMAP PREVIOUS MODSEQ: 9949
>>> [18090][2017-08-16T17:13:48+02:00] >>>: IMAP status:
>>> a:4:{s:8:"messages";i:2861;s:7:"uidnext";i:9657;s:11:"uidvalidity";i:1422798076;s:13:"highestmodseq"
>>> i:9949;}
>>> [18090][2017-08-16T17:13:48+02:00] >>>: STATE: Found 0 message
>>> changes in F206e2a41.
>>> [18090][2017-08-16T17:13:48+02:00] O: <Folder>
>>> [18090][2017-08-16T17:13:48+02:00] O: <SyncKey>
>>> [18090][2017-08-16T17:13:48+02:00] O:
>>> {598d3841-f734-43c4-ab1d-4985bc442056}26
>>> [18090][2017-08-16T17:13:48+02:00] O: </SyncKey>
>>> [18090][2017-08-16T17:13:48+02:00] O: <FolderId>
>>> [18090][2017-08-16T17:13:48+02:00] O: F206e2a41
>>> [18090][2017-08-16T17:13:48+02:00] O: </FolderId>
>>> [18090][2017-08-16T17:13:48+02:00] O: <Status>
>>> [18090][2017-08-16T17:13:48+02:00] O: 1
>>> [18090][2017-08-16T17:13:48+02:00] O: </Status>
>>> [18090][2017-08-16T17:13:48+02:00] O: </Folder>
>>> [18090][2017-08-16T17:13:48+02:00] >>>: Collection output peak
>>> memory usage: 2097152
>>> [18090][2017-08-16T17:13:48+02:00] O: </Folders>
>>> [18090][2017-08-16T17:13:48+02:00] O: </Synchronize>
>>> [18090][2017-08-16T17:13:48+02:00] >>>: STATE: Replacing SYNC_CACHE
>>> entry for user lucas at domain.tld and device 4E6F746573444546414345:
>>> a:11:{s:18:"confirmed_synckeys";a:1:{s:40:"{598d3823-a458-4efe-a027-4985bc442056}28";b:1;}s:17:"last
>>> bsyncstarted";b:0;s:17:"lastsyncendnormal";i:1502896428;s:9:"timestamp";s:10:"1502896428";s:4:"wait"
>>> b:0;s:10:"hbinterval";b:0;s:7:"folders";a:16:{s:9:"T1432e34a";a:3:{s:5:"class";s:5:"Tasks";s:8:"serv
>>> rid";s:7:"@Tasks@";s:4:"type";i:7;}s:2:"RI";a:3:{s:5:"class";s:5:"Email";s:8:"serverid";s:2:"RI";s:4
>>> "type";i:19;}s:6:"OUTBOX";a:3:{s:5:"class";s:5:"Email";s:8:"serverid";s:6:"OUTBOX";s:4:"type";i:6;}s
>>> 9:"Fdbc4791f";a:3:{s:5:"class";s:5:"Email";s:8:"serverid";s:10:"Mailfilter";s:4:"type";i:12;}s:9:"Fb
>>> d19f4e";a:3:{s:5:"class";s:5:"Email";s:8:"serverid";s:22:"shared/service at lmgg.de";s:4:"type";i:12;}s
>>> 9:"Fa5eeb068";a:3:{s:5:"class";s:5:"Email";s:8:"serverid";s:19:"shared/info at lmgg.de";s:4:"type";i:12
>>> }s:9:"Fa5c70c40";a:3:{s:5:"class";s:5:"Email";s:8:"serv
>>> erid";s:4:"Spam";s:4:"type";i:12;}s:9:"F8e9419f1";a:3:{s:5:"class";s:5:"Email";s:8:"serverid";s:8:"C
>>> onjobs";s:4:"type";i:12;}s:9:"F63bb19b2";a:3:{s:5:"class";s:5:"Email";s:8:"serverid";s:25:"shared/ho
>>> tmaster at lmgg.de";s:4:"type";i:12;}s:9:"F5cec613d";a:3:{s:5:"class";s:5:"Email";s:8:"serverid";s:5:"T
>>> ash";s:4:"type";i:12;}s:9:"F5269a6fa";a:3:{s:5:"class";s:5:"Email";s:8:"serverid";s:6:"Drafts";s:4:"
>>> ype";i:3;}s:9:"F24089941";a:3:{s:5:"class";s:5:"Email";s:8:"serverid";s:4:"Sent";s:4:"type";i:5;}s:9
>>> "F206e2a41";a:3:{s:5:"class";s:5:"Email";s:8:"serverid";s:5:"INBOX";s:4:"type";i:2;}s:9:"F0a21fe11";
>>> :3:{s:5:"class";s:5:"Email";s:8:"serverid";s:23:"shared/security at lmgg.de";s:4:"type";i:12;}s:9:"Cd64
>>> c83c";a:3:{s:5:"class";s:8:"Contacts";s:8:"serverid";s:10:"@Contacts@";s:4:"type";i:9;}s:9:"A11d65f8
>>> ";a:3:{s:5:"class";s:8:"Calendar";s:8:"serverid";s:32:"Calendar:fRxzKnAs_boufjYeAsZDCnX";s:4:"type";
>>> :8;}}s:9:"hierarchy";s:39:"{598d3820-59e0-48f7-a957-49b5bc442056}1";s:11:"collections"
>>> ;
>>> a
>>> :2:{s:9:"T1432e34a";a:9:{s:5:"class";s:5:"Tasks";s:10:"windowsize";i:100;s:11:"mimesupport";i:0;s:8:
>>> conflict";s:1:"1";s:9:"bodyprefs";a:1:{i:1;a:2:{s:4:"type";s:1:"1";s:14:"truncationsize";s:7:"104857
>>> ";}}s:8:"serverid";s:7:"@Tasks@";s:11:"lastsynckey";s:40:"{598d3823-a458-4efe-a027-4985bc442056}28";
>>> :14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";}s:9:"F206e2a41";a:9:{s:5:"class";s:5:"Email";s:1
>>> :"windowsize";i:100;s:11:"mimesupport";i:0;s:8:"conflict";s:1:"1";s:9:"bodyprefs";a:1:{i:1;a:2:{s:4:
>>> type";s:1:"1";s:14:"truncationsize";s:1:"0";}}s:8:"serverid";s:5:"INBOX";s:11:"lastsynckey";s:40:"{5
>>> 8d3841-f734-43c4-ab1d-4985bc442056}26";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"5";}}s:13:"p
>>> ngheartbeat";b:0;s:14:"synckeycounter";a:0:{}}
>>> [18090][2017-08-16T17:13:48+02:00] INFO: Maximum memory usage for
>>> ActiveSync request: 2097152 bytes.
>>> 
>>> I think, this is a central point:
>>> 
>>> [18090][2017-08-16T17:13:48+02:00] NOTICE: Message gone or error
>>> reading message from server: Tasks not found
>>> 
>>> But I really don't know what this means, or what I should read in it.
>> 
>> Sorry for the delay in response.
>> 
>> It looks like the server is looking for a task that has been
>> deleted. I'm confused though, because it also appears to *know* it's
>> deleted, since it's sending a DELETE command. I'll have to look into
>> this to see if we are missing catching some exception somewhere.
> 
> Fixed in Horde_Core. New release running as we speak.

You are great. Thank you very much!

More information about the horde mailing list