[horde] Horde Activesync with Outlook 2013/2016: duplicate folders on folder creation
Michael J Rubinsky
mrubinsk at horde.org
Tue Nov 15 12:57:35 UTC 2016
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).
>
> 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: 2007 bytes
Desc: S/MIME Signature
URL: <https://lists.horde.org/archives/horde/attachments/20161115/2e39c030/attachment-0001.bin>
More information about the horde
mailing list