[horde] Horde Activesync with Outlook 2013/2016: duplicate folders on folder creation

Mon Nov 14 17:53:48 UTC 2016

Quoting Hendrik Noack <hn at axxedia-it.de>:

>> -----Ursprüngliche Nachricht-----
>> Von: horde [mailto:horde-bounces at lists.horde.org] Im Auftrag von Michael
>> J Rubinsky
>> Gesendet: Montag, 14. November 2016 14:56
>> An: horde at lists.horde.org
>> Betreff: Re: [horde] Horde Activesync with Outlook 2013/2016: duplicate
>> folders on folder creation
>>
>>
>> Quoting Hendrik Noack <hn at axxedia-it.de>:
>>
>> > -----Ursprüngliche Nachricht-----
>> > Quoting Hendrik Noack <hn at axxedia-it.de>:
>> >
>> >> Hello everbody,
>> >>
>> >>
>> >>
>> >> I did not find anything on the internet on that topic, so I hope
>> >> maybe someone has seen this behaviour before.
>> >>
>> >>
>> >>
>> >> We are using Debian 8 with Dovecot, Exim and Horde on top. The Horde
>> >> installation is up to date. Our clients are using Outlook 2013/2016
>> >> to connect via Horde activesync.
>> >>
>> >> We also have a bunch of iOS and Android devices.
>> >>
>> >>
>> >>
>> >> It’s not always happening, but too often to be just a rare bug. When
>> >> an Outlook client creates a new mail folder, the folder is created,
>> >> then a
>> > sync
>> >> against horde is started and the very same folder is immediately
>> >> sync‘ed back as another new folder. The client now has: folder and
>> >> folder [1] in
>> > the
>> >> database. Right after the appearances of the duplicate folder a new
>> >> sync
>> > is
>> >> started that clears out all mails, contacts and appointments from
>> >> that client, so that Outlook appears to be empty. A reset in
>> >> activesync for
>> > that
>> >> client on the server side does not clear this problem, I have to
>> >> delete
>> > the
>> >> local .ost cache for that client. The next sync will then bring back
>> >> all mails, contacts and appointment. The duplicate folder is also
>> >> gone, as it was never duplicated on the server side.
>> >>
>> >>
>> >>
>> >> Creating a folder in IMP always works as it should, I have never seen
>> >> duplicates in Outlook when I created the folder in IMP.
>> >>
>> >>
>> >>
>> >> My guess is, that there must be something going terribly wrong
>> >> sometimes when Outlook tries to create a new folder via activesync.
>> >> Or maybe this a problem with my Dovecot configuraiton, but like I
>> >> said, the duplicates
>> > never
>> >> reach the server.
>> >
>> > Please provide a sync log for further assistance.
>> >
>> >
>> >
>> >> Any ideas would be much appreciated.
>> >>
>> >>
>> >>
>> >>
>> >>
>> >> Mit freundlichen Grüßen / Best regards
>> >>
>> >> Hendrik Noack
>> >>
>> >>
>> >>
>> >> --
>> >> Axxedia IT GmbH
>> >>
>> >> Schwabstr. 30
>> >> 71106 Magstadt
>> >>
>> >> Tel +49 7159 4979763
>> >> Fax +49 7159 4979775
>> >>
>> >> Mail hn at axxedia-it.de <mailto:hn at axxedia-it.de>
>> >>
>> >> Sitz der Gesellschaft: Schwabstr. 30, 71106 Magstadt
>> >> Geschäftsführer: Hendrik Noack . Amtsgericht: Stuttgart HRB 727296
>> >
>> >
>> >
>> > --
>> > 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: smime.p7s
>> > Type: application/pkcs7-signature
>> > Size: 2007 bytes
>> > Desc: S/MIME Signature
>> > URL:
>> > <https://lists.horde.org/archives/horde/attachments/20161111/95c29217/
>> > attach
>> > ment.bin>
>> >
>> >
>> > Hi Mike,
>> >
>> > your help is always appreciated. Murphy's law is a bi***, I had to
>> > bother a few people until it was my private Outlook  that did me a
>> > favor and created a duplicate folder (got a small horde install with
>> > dovecot and exim in my basement too, just for fun).
>> >
>> > The log file is pretty big, so I decided to put it into my owncloud.
>> > https://www.schwabstrasse.me/owncloud/s/Uk5Dn7x0YWuftT4
>>
>> File Not Found.
>
> Ouch, you are right. Here we go:
> https://www.schwabstrasse.me/owncloud/s/MUjejC0cNY8aaDS
>
> I set the expiration date too soon.
>
>
>>
>>
>> > I created a subfolder Test in Belege. Search for "Belege.Test"
>> >
>> > Btw my private Outlook client is not using Active Directory
>> > authentication via pam, but pam is used for local authentication, and
>> > I still don't think it's related to authentication, but who knows...

There seems to be something wrong on your system with creating the new  
ActiveSync UID for the new mail folder. Normally, this is what the log  
would show when creating a new folder:

2016-11-14T17:07:42+00:00 INFO: [10032]  
Horde_ActiveSync_Connector_Importer::importFolderChange(, NewTest,  
F956400b5, 12)
2016-11-14T17:07:42+00:00 INFO: [10032]  
Horde_Core_ActiveSync_Driver::changeFolder(, NewTest, test, , 12)
2016-11-14T17:07:43+00:00 INFO: [10032] Polling  
Horde_Core_ActiveSync_Driver::_getMailFolders()
2016-11-14T17:07:43+00:00 INFO: [10032] Creating new folder uuid for  
test/NewTest: Fb9f3cfda
2016-11-14T17:07:43+00:00 INFO: [10032] Updating state during change
2016-11-14T17:07:43+00:00 INFO: [10032] Collection without id found:  
a:1:{s:8:"serverid";s:12:"test/NewTest";}
2016-11-14T17:07:43+00:00 INFO: [10032] Updating serverid in folder  
state. Setting test/NewTest for Fb9f3cfda.

Notice the line about "Creating new folder uuid...". In your log, this  
doesn't happen so we can't send the new UUID to the client, and it's  
not "attached" to the new server-side folder:

2016-11-11T15:14:47+01:00 INFO: [10222]  
Horde_ActiveSync_Connector_Importer::importFolderChange(, Test,  
F9fe7ff65, 12)
2016-11-11T15:14:47+01:00 INFO: [10222]  
Horde_Core_ActiveSync_Driver::changeFolder(, Test, Belege, , 12)
2016-11-11T15:14:47+01:00 INFO: [10222] Polling  
Horde_Core_ActiveSync_Driver::_getMailFolders()
2016-11-11T15:14:47+01:00 INFO: [10222] Updating state during change
2016-11-11T15:14:47+01:00 INFO: [10222] Collection without id found:  
a:1:{s:8:"serverid";s:0:"";}
2016-11-11T15:14:47+01:00 INFO: [10222] Updating serverid in folder  
state. Setting  for .

However, when the next FOLDERSYNC is done, it notices the new folder,  
and since the uuid create failed earlier, it is sent to the client as  
a NEW folder. As to WHY your installation is behaving this way, I  
don't know. I can't reproduce this locally on any of my test systems  
and test clients - including Outlook. I will try to find some places  
to add some more useful logging to see if we can track down the  
reason. What version of Horde_Core is on your system?

-- 
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: smime.p7s
Type: application/pkcs7-signature
Size: 2007 bytes
Desc: S/MIME Signature
URL: <https://lists.horde.org/archives/horde/attachments/20161114/7cdaa7da/attachment-0001.bin>