[horde] iOS Mail Spam support

Michael J Rubinsky mrubinsk at horde.org
Sat Oct 27 18:27:55 UTC 2018 

Quoting Twilek <twilek at gmx.de>:

>> If you provide a sync log, I can investigate. I do know that iOS  
>> has/had some strange assumptions and requirements >about folder  
>> names when using ActiveSync, but I can take a look to see for sure  
>> what is going on with your situation.
>
> @Kevin Thank you for your reply, I seem to have missed your posts  
> back then, but as you have indicated you didn´t find a satisfactory  
> answer so maybe it is worth to give it another shot.
>
> @Mike I have turned on detailed active sync logging and then marked  
> a mail as spam in iOS. Apache und Dovecot logs don´t show anything  
> interesting. The activesync log is from the moment I marked the  
> message to the moment when I think it started to resynched  
> everything. There is a german error message in there "Das Element  
> konnte nicht gespeichert werden, weil es bereits existiert." which  
> translates to roughly "The element could not be saved because it  
> already exists." I have redacted some personal info in the log. I  
> hope this sheds some light on the problem.

There was a bug in our code that caused an exception to be thrown when  
subscribing to the Junk folder after attempting to create it, if it  
already existed. I've fixed this and released a new ActiveSync package.

Not 100% sure this is _everything_ that is causing issues with the  
Junk folder, but it will get you past the errors that are being thrown  
in your sync log and causing the hierarchy to be resynchronized.




> [15035][2018-10-23T07:39:14+00:00] INFO:  
> ----------Horde_Core_ActiveSync_Driver::authenticate() attempt for  
> twilek----------
> [15035][2018-10-23T07:39:14+00:00] INFO: ----------FOLDERCREATE  
> request received for user twilek
> [15035][2018-10-23T07:39:14+00:00] >>>: STATE: Device entry exists  
> for EH3UOVHI8T0O920KDUT25U6O7K, updating userAgent, version, and  
> supported.
> [15035][2018-10-23T07:39:14+00:00] INFO: ----------Request being  
> handled for device: EH3UOVHI8T0O920KDUT25U6O7K, Supporting protocol  
> version: 14.1, Using Horde_ActiveSync v2.39.3
> [15035][2018-10-23T07:39:14+00:00] >>>: GET VARIABLES: Array
> (
>     [User] => twilek
>     [DeviceId] => EH3UOVHI8T0O920KDUT25U6O7K
>     [DeviceType] => iPad
>     [Cmd] => FolderCreate
> )
>
> [15035][2018-10-23T07:39:14+00:00] >>>: Handling  
> FOLDER[CREATE|DELETE|CHANGE] command.
> [15035][2018-10-23T07:39:14+00:00] I: <FolderHierarchy:FolderCreate>
> [15035][2018-10-23T07:39:14+00:00] I:  <FolderHierarchy:SyncKey>
> [15035][2018-10-23T07:39:14+00:00] I:     
> {5bcecdac-86e8-47e3-9011-2c32bc4426b0}1
> [15035][2018-10-23T07:39:14+00:00] I:  </FolderHierarchy:SyncKey>
> [15035][2018-10-23T07:39:14+00:00] I:  <FolderHierarchy:ParentId>
> [15035][2018-10-23T07:39:14+00:00] I:    0
> [15035][2018-10-23T07:39:14+00:00] I:  </FolderHierarchy:ParentId>
> [15035][2018-10-23T07:39:14+00:00] I:  <FolderHierarchy:DisplayName>
> [15035][2018-10-23T07:39:14+00:00] I:    Junk
> [15035][2018-10-23T07:39:14+00:00] I:  </FolderHierarchy:DisplayName>
> [15035][2018-10-23T07:39:14+00:00] I:  <FolderHierarchy:Type>
> [15035][2018-10-23T07:39:14+00:00] I:    12
> [15035][2018-10-23T07:39:14+00:00] I:  </FolderHierarchy:Type>
> [15035][2018-10-23T07:39:14+00:00] I: </FolderHierarchy:FolderCreate>
> [15035][2018-10-23T07:39:14+00:00] >>>: Creating new  
> Horde_ActiveSync_SyncCache.
> [15035][2018-10-23T07:39:14+00:00] >>>: STATE: Loading state for  
> synckey {5bcecdac-86e8-47e3-9011-2c32bc4426b0}1
> [15035][2018-10-23T07:39:14+00:00] >>>: STATE: Loading FOLDERSYNC  
> state containing 45 folders.
> [15035][2018-10-23T07:39:14+00:00] >>>:  
> Horde_ActiveSync_Connector_Importer::importFolderChange(, Junk, 0, 12)
> [15035][2018-10-23T07:39:14+00:00] >>>:  
> Horde_Core_ActiveSync_Driver::changeFolder(, Junk, 0, , 12)
> [15035][2018-10-23T07:39:14+00:00] WARN: Mailbox Junk already  
> exists, subscribing to it.
> [15035][2018-10-23T07:39:14+00:00] ERR: Das Element konnte nicht  
> gespeichert werden, weil es bereits existiert.
> [15035][2018-10-23T07:39:14+00:00] ERR: Das Element konnte nicht  
> gespeichert werden, weil es bereits existiert.
> [15035][2018-10-23T07:39:14+00:00] ERR: Das Element konnte nicht  
> gespeichert werden, weil es bereits existiert.
> [15035][2018-10-23T07:39:14+00:00] O: <FolderHierarchy:FolderCreate>
> [15035][2018-10-23T07:39:14+00:00] O:  <FolderHierarchy:Status>
> [15035][2018-10-23T07:39:14+00:00] O:   6
> [15035][2018-10-23T07:39:14+00:00] O:  </FolderHierarchy:Status>
> [15035][2018-10-23T07:39:14+00:00] O: </FolderHierarchy:FolderCreate>
> [15035][2018-10-23T07:39:14+00:00] INFO: Maximum memory usage for  
> ActiveSync request: 2097152 bytes.
> [11314][2018-10-23T07:39:14+00:00] INFO:  
> ----------Horde_Core_ActiveSync_Driver::authenticate() attempt for  
> twilek----------
> [11314][2018-10-23T07:39:14+00:00] INFO: ----------FOLDERCREATE  
> request received for user twilek
> [11314][2018-10-23T07:39:14+00:00] >>>: STATE: Device entry exists  
> for EH3UOVHI8T0O920KDUT25U6O7K, updating userAgent, version, and  
> supported.
> [11314][2018-10-23T07:39:14+00:00] INFO: ----------Request being  
> handled for device: EH3UOVHI8T0O920KDUT25U6O7K, Supporting protocol  
> version: 14.1, Using Horde_ActiveSync v2.39.3
> [11314][2018-10-23T07:39:14+00:00] >>>: GET VARIABLES: Array
> (
>     [User] => twilek
>     [DeviceId] => EH3UOVHI8T0O920KDUT25U6O7K
>     [DeviceType] => iPad
>     [Cmd] => FolderCreate
> )
>
> [11314][2018-10-23T07:39:14+00:00] >>>: Handling  
> FOLDER[CREATE|DELETE|CHANGE] command.
> [11314][2018-10-23T07:39:14+00:00] I: <FolderHierarchy:FolderCreate>
> [11314][2018-10-23T07:39:14+00:00] I:  <FolderHierarchy:SyncKey>
> [11314][2018-10-23T07:39:14+00:00] I:     
> {5bcecdac-86e8-47e3-9011-2c32bc4426b0}1
> [11314][2018-10-23T07:39:14+00:00] I:  </FolderHierarchy:SyncKey>
> [11314][2018-10-23T07:39:14+00:00] I:  <FolderHierarchy:ParentId>
> [11314][2018-10-23T07:39:14+00:00] I:    0
> [11314][2018-10-23T07:39:14+00:00] I:  </FolderHierarchy:ParentId>
> [11314][2018-10-23T07:39:14+00:00] I:  <FolderHierarchy:DisplayName>
> [11314][2018-10-23T07:39:14+00:00] I:    Junk
> [11314][2018-10-23T07:39:14+00:00] I:  </FolderHierarchy:DisplayName>
> [11314][2018-10-23T07:39:14+00:00] I:  <FolderHierarchy:Type>
> [11314][2018-10-23T07:39:14+00:00] I:    12
> [11314][2018-10-23T07:39:14+00:00] I:  </FolderHierarchy:Type>
> [11314][2018-10-23T07:39:14+00:00] I: </FolderHierarchy:FolderCreate>
> [11314][2018-10-23T07:39:14+00:00] >>>: Creating new  
> Horde_ActiveSync_SyncCache.
> [11314][2018-10-23T07:39:14+00:00] >>>: STATE: Loading state for  
> synckey {5bcecdac-86e8-47e3-9011-2c32bc4426b0}1
> [11314][2018-10-23T07:39:14+00:00] >>>: STATE: Loading FOLDERSYNC  
> state containing 45 folders.
> [11314][2018-10-23T07:39:14+00:00] >>>:  
> Horde_ActiveSync_Connector_Importer::importFolderChange(, Junk, 0, 12)
> [11314][2018-10-23T07:39:14+00:00] >>>:  
> Horde_Core_ActiveSync_Driver::changeFolder(, Junk, 0, , 12)
> [11314][2018-10-23T07:39:14+00:00] WARN: Mailbox Junk already  
> exists, subscribing to it.
> [11314][2018-10-23T07:39:14+00:00] ERR: Das Element konnte nicht  
> gespeichert werden, weil es bereits existiert.
> [11314][2018-10-23T07:39:14+00:00] ERR: Das Element konnte nicht  
> gespeichert werden, weil es bereits existiert.
> [11314][2018-10-23T07:39:14+00:00] ERR: Das Element konnte nicht  
> gespeichert werden, weil es bereits existiert.
> [11314][2018-10-23T07:39:14+00:00] O: <FolderHierarchy:FolderCreate>
> [11314][2018-10-23T07:39:14+00:00] O:  <FolderHierarchy:Status>
> [11314][2018-10-23T07:39:14+00:00] O:   6
> [11314][2018-10-23T07:39:14+00:00] O:  </FolderHierarchy:Status>
> [11314][2018-10-23T07:39:14+00:00] O: </FolderHierarchy:FolderCreate>
> [11314][2018-10-23T07:39:14+00:00] INFO: Maximum memory usage for  
> ActiveSync request: 2097152 bytes.
> [15037][2018-10-23T07:39:15+00:00] INFO:  
> ----------Horde_Core_ActiveSync_Driver::authenticate() attempt for  
> twilek----------
> [15037][2018-10-23T07:39:15+00:00] INFO: ----------FOLDERSYNC  
> request received for user twilek
> [15037][2018-10-23T07:39:15+00:00] >>>: STATE: Device entry exists  
> for EH3UOVHI8T0O920KDUT25U6O7K, updating userAgent, version, and  
> supported.
> [15037][2018-10-23T07:39:15+00:00] INFO: ----------Request being  
> handled for device: EH3UOVHI8T0O920KDUT25U6O7K, Supporting protocol  
> version: 14.1, Using Horde_ActiveSync v2.39.3
> [15037][2018-10-23T07:39:15+00:00] >>>: GET VARIABLES: Array
> (
>     [User] => twilek
>     [DeviceId] => EH3UOVHI8T0O920KDUT25U6O7K
>     [DeviceType] => iPad
>     [Cmd] => FolderSync
> )
>
> [15037][2018-10-23T07:39:15+00:00] >>>: Handling FOLDERSYNC command.
> [15037][2018-10-23T07:39:15+00:00] >>>: Checking policykey for  
> device: EH3UOVHI8T0O920KDUT25U6O7K user: twilek
> [15037][2018-10-23T07:39:15+00:00] INFO: Policykey: 0 verified.
> [15037][2018-10-23T07:39:15+00:00] I: <FolderHierarchy:FolderSync>
> [15037][2018-10-23T07:39:15+00:00] I:  <FolderHierarchy:SyncKey>
> [15037][2018-10-23T07:39:15+00:00] I:    0
> [15037][2018-10-23T07:39:15+00:00] I:  </FolderHierarchy:SyncKey>
> [15037][2018-10-23T07:39:15+00:00] >>>: Creating new  
> Horde_ActiveSync_SyncCache.
> [15037][2018-10-23T07:39:15+00:00] NOTICE:  
> Horde_ActiveSync_State_Base::loadState: clearing folder state.
> [15037][2018-10-23T07:39:15+00:00] >>>: STATE: Resetting device  
> state for device: EH3UOVHI8T0O920KDUT25U6O7K, user: twilek, and  
> collection: foldersync.
> [15037][2018-10-23T07:39:15+00:00] >>>: Creating new  
> Horde_ActiveSync_SyncCache.
> [15037][2018-10-23T07:39:15+00:00] NOTICE: STATE: Clearing  
> foldersync state from synccache.
> [15037][2018-10-23T07:39:15+00:00] >>>: Clearing collections data from cache.
> [15037][2018-10-23T07:39:15+00:00] >>>: STATE: Replacing SYNC_CACHE  
> entry for user twilek and device EH3UOVHI8T0O920KDUT25U6O7K:  
> a:11:{s:18:"confirmed_synckeys";a:1:{s:39:"{5bcecff2-af54-4744-8a89-3eedbc442$
> [15037][2018-10-23T07:39:15+00:00] I: </FolderHierarchy:FolderSync>
> [15037][2018-10-23T07:39:15+00:00] >>>: STATE: Initializing folder  
> diff engine
> [15037][2018-10-23T07:39:15+00:00] >>>:  
> Horde_Core_ActiveSync_Driver::getFolderList()
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Calendar:P9lpdOmkpHVOE3ZazA7FVjA: A69eeea86
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Calendar:JembTemkpHVOE4IatLR3YkA: Aa082f64c
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Calendar:BjZpCOmkpHVOE3YJR0NFVjA: A67c624a0
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Calendar:iutCW_mkpHVOE4Iul793YkA: Ab9febb32
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Calendar:rrixBemkpHVOE3Xt2uTlVjA: A289656ed
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Calendar:bZVDdOmkpHVOE3Y_nhclVjA: Ac6ac1a13
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Contacts:35398753d7d06da67e7228b547ac8cd0: Ce471caac
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Contacts:qDW02dCSPmLn2rKJ1cGtSYw: C9343af7f
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Tasks:JmFL6zJrQlKCrnZyH24i9KQ: T1bda47bc
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Tasks:twilek: Tb6aa04b6
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> @Notes@: N2a6e5966
> [15037][2018-10-23T09:39:15+02:00] >>>: Polling  
> Horde_Core_ActiveSync_Driver::_getMailFolders()
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> INBOX: F9cbc1cc3
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Axxxr: F709124f8
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> x: F507e46b4
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Arbeit: F2f5ebf4f
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Archive: F6369de72
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Cxxs: F8a88b349
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Cron: F09e21606
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> xx: Fe56f5ecc
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Ebay: Fdeb3928c
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> x: F0533c291
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Drafts: Fc4d6eec6
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Sent Messages: F7536e9f6
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Haus: F627e0d93
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> Horde: F325801f6
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> xxx: Fb514ccfe
> [15037][2018-10-23T09:39:15+02:00] >>>: Creating new folder uuid for  
> xxx: Fa14d5508
> -- 
> 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
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-keys
Size: 9272 bytes
Desc: PGP Public Key
URL: <https://lists.horde.org/archives/horde/attachments/20181027/03cdc62f/attachment-0002.bin>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 821 bytes
Desc: PGP Digital Signature
URL: <https://lists.horde.org/archives/horde/attachments/20181027/03cdc62f/attachment-0003.bin>

More information about the horde mailing list