[horde] Horde Activesync with Outlook 2013/2016: duplicate folders on folder creation
Hendrik Noack
hn at axxedia-it.de
Tue Nov 15 14:35:33 UTC 2016
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
Sitz der Gesellschaft: Schwabstr. 30, 71106 Magstadt
Geschäftsführer: Hendrik Noack . Amtsgericht: Stuttgart HRB 727296
> -----Ursprüngliche Nachricht-----
> Von: horde [mailto:horde-bounces at lists.horde.org] Im Auftrag von Michael
> J Rubinsky
> Gesendet: Dienstag, 15. November 2016 13:58
> 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>:
>
> > 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
> >
> > Sitz der Gesellschaft: Schwabstr. 30, 71106 Magstadt
> > Geschäftsführer: Hendrik Noack . Amtsgericht: Stuttgart HRB 727296
> >
> >> -----Ursprüngliche Nachricht-----
> >> Von: horde [mailto:horde-bounces at lists.horde.org] Im Auftrag von
> >> Michael J Rubinsky
> >> Gesendet: Dienstag, 15. November 2016 02:00
> >> An: horde at lists.horde.org
> >> Betreff: Re: [horde] Horde Activesync with Outlook 2013/2016:
> >> duplicate folders on folder creation
> >>
> >>
> >> Quoting Michael J Rubinsky <mrubinsk at horde.org>:
> >>
> >> > Quoting hn at axxedia-it.de:
> >> >
> >> >> Zitat von Michael J Rubinsky <mrubinsk at horde.org>:
> >> >>
> >> >>> 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 21:33
> >> >>>>> 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-----
> >> >>>>>>> Von: horde [mailto:horde-bounces at lists.horde.org] Im Auftrag
> >> von
> >> >>>>>>> Michael J Rubinsky
> >> >>>>>>> Gesendet: Montag, 14. November 2016 19:39
> >> >>>>>>> An: horde at lists.horde.org
> >> >>>>>>> Betreff: Re: [horde] Horde Activesync with Outlook 2013/2016:
> >> >>>>>>> duplicate folders on folder creation
> >> >>>>>>>
> >> >>>>>>>
> >> >>>>>>> Quoting Michael J Rubinsky <mrubinsk at horde.org>:
> >> >>>>>>>
> >> >>>>>>>> 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
> >> >>>>>>>>>>> /95c
> >> >>>>>>>>>>> 292
> >> >>>>>>>>>>> 17/
> >> >>>>>>>>>>> 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?
> >> >>>>>>>
> >> >>>>>>> Try updating to the just released Horde_Core package. See if
> >> >>>>>>> it helps, or at least gives us a better idea of what is happening.
> >> >>>>>>
> >> >>>>>> Sync is done and I created a new folder NewTest. It instantly
> >> >>>>>> duplicated the folder again.
> >> >>>>>> I see two new lines in the log with ERR: but they are empty
> >> >>>>>> afterwards...
> >> >>>>>
> >> >>>>>
> >> >>>>> The blank message was from a typo, but that's fine, because I
> >> >>>>> know where it's failing for you now at least - though I don't know
> why.
> >> >>>>> Also, your log has a weird entry in it:
> >> >>>>>
> >> >>>>>
> >> >>>>> 2016-11-14T20:33:46+01:00 INFO: [4591] Creating new
> >> >>>>> Horde_ActiveSync_SyncCache.
> >> >>>>> 2016-11-14T20:33:46+01:00 INFO: [4591] Loading state for
> >> >>>>> synckey
> >> >>>>> {582a0f63-9520-494c-b490-1320c0a80202}3
> >> >>>>> 2016-11-14T20:33:46+01:00 INFO: [4591] Loading FOLDERSYNC state
> >> >>>>> containing 54 folders.
> >> >>>>> 2016-11-14T20:33:46+01:00 INFO: [4591]
> >> >>>>> Horde_ActiveSync_Connector_Importer::importFolderChange(,
> >> NewTest,
> >> >>>>> Fa261a33a, 12)
> >> >>>>> 2016-11-14T20:33:46+01:00 INFO: [4591]
> >> >>>>> Horde_Core_ActiveSync_Driver::changeFolder(, NewTest, Belege,
> ,
> >> >>>>> 12)
> >> >>>>> 2016-11-14T20:33:46+01:00 INFO: [4591] New IMAP folder created:
> >> >>>>> Belege.NewTest
> >> >>>>> 2016-11-14T20:33:46+01:00 INFO: [4591] Device entry exists for
> >> >>>>> BB90462ADAB445BE964429B4F6244FFD, updating userAgent,
> version,
> >> and
> >> >>>>> supported.
> >> >>>>>
> >> >>>>> ^^^ This entry is from code that saves device properties and
> >> >>>>> happens much earlier in the request. In fact, your log shows it
> >> >>>>> happening in the correct place also. There is absolutely no
> >> >>>>> code path that would trigger this portion of the code to run
> >> >>>>> immediately after the IMAP folder was created.
> >> >>>>>
> >> >>>>> The issue in your setup for some reason is the folder list
> >> >>>>> doesn't return the new folder when it should. Probably
> >> >>>>> returning a cached version of it, but what's curious is that
> >> >>>>> the folder list should not have been loaded at that point in
> >> >>>>> the request yet, so it shouldn't have been cached.
> >> >>>>>
> >> >>>>> You're not running apache mpm-worker with mod_php by any
> chance
> >> >>>>> are you?
> >> >>>>
> >> >>>> No I'm not running mpm-worker, but I'm using memcache for my
> >> owncloud.
> >> >>>
> >> >>> I have no idea what's causing this then. At the very least, we
> >> >>> now send back an appropriate error code to the client to indicate
> >> >>> there was a problem. However, Outlook seems to be ignoring it and
> >> >>> keeping the newly created folder in the UI. I'll keep looking,
> >> >>> but I'm running out of ideas.
> >> >>>
> >> >>
> >> >> I'm fighting this problem for a while now. For some clients it's
> >> >> ok to just clear the .ost file and do a complete sync to get rid
> >> >> of the duplicates for a while, for others it doesn't work at all
> >> >> (like my private Outlook client).
> >> >>
> >> >> If you need anything else, config files for apache, php, dovecot
> >> >> or whatever helps to find the issue here, I will gladly offer them
> >> >> to you.
> >>
> >> Ok. Try this. In Horde_Core_ActiveSync_Driver::_getMailFolders(),
> >> line
> >> 3234 add a parameter true so the line looks like so:
> >>
> >> $imap_folders = $this->_imap->getMailboxes(true);
> >>
> >> And see if that helps. I have no idea how or why your folder tree is
> >> loaded before this point, but it's the only logical explanation for
> >> what I'm seeing in your logs.
> >
> > OK found the right place and made the change, but it is still
> > duplicating folders (NewTestTwo).
>
> Well, this likely means that the IMAP server is not returning the new folder
> when we ask for the list of folders. We can see what folder list your IMAP
> server is returning by adding the following line directly below the line you
> just edited previously:
>
> Horde::debug($imap_folders);
>
> This will put a file named horde_debug.txt in your temporary directory.
>
> One last idea, if you are using IMAP subscriptions in IMP, try turning that off
> in the settings - or if you are not using them, try turning it on (when using
> subscriptions, ActiveSync only returns subscribed folders - perhaps your
> IMAP server has some sort of bug in this regard).
I added the debug command and got the output from the imap folder list.
https://www.schwabstrasse.me/owncloud/s/jDz6df9Ss351PJk
The new folder is NewTestThree. You can see that the first two attempts don't show the new folder, but it is there on the third attempt.
IMAP subscriptions were enabled in IMP, but it did not make any difference turning them off.
>
>
>
> >
> > 2016-11-15T08:57:23+00:00 INFO: [24478]
> > Horde_Core_ActiveSync_Driver::authenticate() attempt for hendrik
> > 2016-11-15T09:57:23+01:00 INFO: [24478] FOLDERCREATE request received
> > for user hendrik
> > 2016-11-15T09:57:23+01:00 INFO: [24478] Device entry exists for
> > BB90462ADAB445BE964429B4F6244FFD, updating userAgent, version, and
> > supported.
> > 2016-11-15T09:57:23+01:00 INFO: [24478] Request being handled for
> > device: BB90462ADAB445BE964429B4F6244FFD, Supporting protocol
> > version: 14.0, Using Horde_ActiveSync v2.37.3
> > 2016-11-15T09:57:23+01:00 INFO: [24478] GET VARIABLES: Array (
> > [Cmd] => FolderCreate
> > [User] => hendrik
> > [DeviceId] => BB90462ADAB445BE964429B4F6244FFD
> > [DeviceType] => WindowsOutlook15
> > )
> >
> > 2016-11-15T09:57:23+01:00 INFO: [BB90462ADAB445BE964429B4F6244FFD]
> > Handling FOLDER[CREATE|DELETE|CHANGE] command.
> > 2016-11-15T09:57:23+01:00 DEBUG: [24478] I
> <FolderHierarchy:FolderCreate>
> > 2016-11-15T09:57:23+01:00 DEBUG: [24478] I <FolderHierarchy:SyncKey>
> > 2016-11-15T09:57:23+01:00 DEBUG: [24478] I
> > {582a0f63-9520-494c-b490-1320c0a80202}7
> > 2016-11-15T09:57:23+01:00 DEBUG: [24478] I </FolderHierarchy:SyncKey>
> > 2016-11-15T09:57:23+01:00 DEBUG: [24478] I <FolderHierarchy:ParentId>
> > 2016-11-15T09:57:23+01:00 DEBUG: [24478] I Fa261a33a
> > 2016-11-15T09:57:23+01:00 DEBUG: [24478] I </FolderHierarchy:ParentId>
> > 2016-11-15T09:57:23+01:00 DEBUG: [24478] I
> <FolderHierarchy:DisplayName>
> > 2016-11-15T09:57:23+01:00 DEBUG: [24478] I NewTestTwo
> > 2016-11-15T09:57:23+01:00 DEBUG: [24478] I
> </FolderHierarchy:DisplayName>
> > 2016-11-15T09:57:23+01:00 DEBUG: [24478] I <FolderHierarchy:Type>
> > 2016-11-15T09:57:23+01:00 DEBUG: [24478] I 12
> > 2016-11-15T09:57:23+01:00 DEBUG: [24478] I </FolderHierarchy:Type>
> > 2016-11-15T09:57:23+01:00 DEBUG: [24478] I
> > </FolderHierarchy:FolderCreate>
> > 2016-11-15T09:57:23+01:00 INFO: [24478] Creating new
> > Horde_ActiveSync_SyncCache.
> > 2016-11-15T09:57:23+01:00 INFO: [24478] Loading state for synckey
> > {582a0f63-9520-494c-b490-1320c0a80202}7
> > 2016-11-15T09:57:23+01:00 INFO: [24478] Loading FOLDERSYNC state
> > containing 52 folders.
> > 2016-11-15T09:57:23+01:00 INFO: [24478]
> > Horde_ActiveSync_Connector_Importer::importFolderChange(,
> > NewTestTwo, Fa261a33a, 12)
> > 2016-11-15T09:57:23+01:00 INFO: [24478]
> > Horde_Core_ActiveSync_Driver::changeFolder(, NewTestTwo, Belege, ,
> 12)
> > 2016-11-15T09:57:23+01:00 INFO: [24478] New IMAP folder created:
> > Belege.NewTestTwo
> > 2016-11-15T09:57:23+01:00 INFO: [24478] Polling
> > Horde_Core_ActiveSync_Driver::_getMailFolders()
> > 2016-11-15T09:57:23+01:00 ERR:
> > 2016-11-15T09:57:23+01:00 ERR:
> > 2016-11-15T09:57:23+01:00 DEBUG: [24478] O
> <FolderHierarchy:FolderCreate>
> > 2016-11-15T09:57:23+01:00 DEBUG: [24478] O <FolderHierarchy:Status>
> > 2016-11-15T09:57:23+01:00 DEBUG: [24478] O 6
> > 2016-11-15T09:57:23+01:00 DEBUG: [24478] O </FolderHierarchy:Status>
> > 2016-11-15T09:57:23+01:00 DEBUG: [24478] O
> > </FolderHierarchy:FolderCreate>
> > 2016-11-15T09:57:23+01:00 INFO: [24478] Maximum memory usage for
> > ActiveSync request: 8912896 bytes.
> > 2016-11-15T08:57:23+00:00 INFO: [24478]
> > Horde_Core_ActiveSync_Driver::authenticate() attempt for hendrik
> > 2016-11-15T09:57:24+01:00 INFO: [24478] FOLDERSYNC request received
> > for user hendrik
> > 2016-11-15T09:57:24+01:00 INFO: [24478] Device entry exists for
> > BB90462ADAB445BE964429B4F6244FFD, updating userAgent, version, and
> > supported.
> > 2016-11-15T09:57:24+01:00 INFO: [24478] Request being handled for
> > device: BB90462ADAB445BE964429B4F6244FFD, Supporting protocol
> > version: 14.0, Using Horde_ActiveSync v2.37.3
> > 2016-11-15T09:57:24+01:00 INFO: [24478] GET VARIABLES: Array (
> > [Cmd] => FolderSync
> > [User] => hendrik
> > [DeviceId] => BB90462ADAB445BE964429B4F6244FFD
> > [DeviceType] => WindowsOutlook15
> > )
> >
> > 2016-11-15T09:57:24+01:00 INFO: [24478] Handling FOLDERSYNC command.
> > 2016-11-15T09:57:24+01:00 INFO: [24478] Checking policykey for
> > device: BB90462ADAB445BE964429B4F6244FFD user: hendrik
> > 2016-11-15T09:57:24+01:00 INFO: [24478] Allowing
> > BB90462ADAB445BE964429B4F6244FFD to connect since
> PROVISIONING_LOOSE
> > is true and is either non-provisionable or has broken provisioning.
> > 2016-11-15T09:57:24+01:00 INFO: [24478] Stored key: 0
> > 2016-11-15T09:57:24+01:00 INFO: [24478] Policykey: verified.
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] I <FolderHierarchy:FolderSync>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] I <FolderHierarchy:SyncKey>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] I
> > {582a0f63-9520-494c-b490-1320c0a80202}7
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] I </FolderHierarchy:SyncKey>
> > 2016-11-15T09:57:24+01:00 INFO: [24478] Creating new
> > Horde_ActiveSync_SyncCache.
> > 2016-11-15T09:57:24+01:00 INFO: [24478] Loading state for synckey
> > {582a0f63-9520-494c-b490-1320c0a80202}7
> > 2016-11-15T09:57:24+01:00 INFO: [24478] Loading FOLDERSYNC state
> > containing 52 folders.
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] I
> > </FolderHierarchy:FolderSync>
> > 2016-11-15T09:57:24+01:00 INFO: [24478] Initializing folder diff
> > engine
> > 2016-11-15T09:57:24+01:00 INFO: [24478]
> > Horde_Core_ActiveSync_Driver::getFolderList()
> > 2016-11-15T09:57:24+01:00 INFO: [24478] Polling
> > Horde_Core_ActiveSync_Driver::_getMailFolders()
> > 2016-11-15T09:57:24+01:00 INFO: [24478] Creating new folder uuid for
> > Belege.NewTestTwo: Fca9b41dd
> > 2016-11-15T09:57:24+01:00 INFO: [24478] Found 1 folder changes.
> > 2016-11-15T09:57:24+01:00 INFO: [24478] Returning folder
> > Belege.NewTestTwo (Fca9b41dd type 12) from
> > Horde_Core_ActiveSync_Driver::getFolder()
> > 2016-11-15T09:57:24+01:00 INFO: [24478] Updating state during
> > foldersync
> > 2016-11-15T09:57:24+01:00 INFO: [24478] Returning folder
> > Belege.NewTestTwo (Fca9b41dd type 12) from
> > Horde_Core_ActiveSync_Driver::getFolder()
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O
> <FolderHierarchy:FolderSync>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O <FolderHierarchy:Status>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O 1
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O </FolderHierarchy:Status>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O <FolderHierarchy:SyncKey>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O
> > {582a0f63-9520-494c-b490-1320c0a80202}8
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O </FolderHierarchy:SyncKey>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O <FolderHierarchy:Changes>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O <FolderHierarchy:Count>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O 1
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O </FolderHierarchy:Count>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O <FolderHierarchy:Add>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O
> > <FolderHierarchy:ServerEntryId>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O Fca9b41dd
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O
> > </FolderHierarchy:ServerEntryId>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O
> <FolderHierarchy:ParentId>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O Fa261a33a
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O
> </FolderHierarchy:ParentId>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O
> <FolderHierarchy:DisplayName>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O NewTestTwo
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O
> </FolderHierarchy:DisplayName>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O <FolderHierarchy:Type>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O 12
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O </FolderHierarchy:Type>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O </FolderHierarchy:Add>
> > 2016-11-15T09:57:24+01:00 INFO: [24478] Collection without id found:
> > a:1:{s:8:"serverid";s:17:"Belege.NewTestTwo";}
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O </FolderHierarchy:Changes>
> > 2016-11-15T09:57:24+01:00 DEBUG: [24478] O
> > </FolderHierarchy:FolderSync>
> >
> >
> >
> >>
> >>
> >>
> >> > You are using sql for $conf[activesync][storage], right?
> >> >
> >> >
> >> > --
> >> > mike
> >> > The Horde Project
> >> > http://www.horde.org
> >> > https://www.facebook.com/hordeproject
> >> > https://www.twitter.com/hordeproject
> >>
> >>
> >>
> >> --
> >> mike
> >> The Horde Project
> >> http://www.horde.org
> >> https://www.facebook.com/hordeproject
> >> https://www.twitter.com/hordeproject
>
>
>
> --
> 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: 5735 bytes
Desc: not available
URL: <https://lists.horde.org/archives/horde/attachments/20161115/27e6901e/attachment-0001.bin>
More information about the horde
mailing list