[horde] Problems with Active Sync and Outlook 2013/2016

Lucas (wb7) lg at wb7.eu
Sat Nov 14 14:45:16 UTC 2015


> Quoting Michael J Rubinsky <mrubinsk at horde.org>:
> 
> > Quoting Lucas (wb7) <lg at wb7.eu>:
> >
> >> Hi everyone,
> >>
> >> here is a wiretrace and I hope this is what you needed:
> >>
> >> http://pastebin.com/f24cQn0z
> >
> >
> > We need the raw trace, not exported as plaintext, as it's not possible
> > to see the wbxml data otherwise.
> 
> Since this seems to be a FOLDERSYNC request, it *might* be related to a PHP
> bug others are reporting that is causing LIST commands to fail on the IMAP
> server. What PHP version are you using?  What does your IMAP debug log
> look like?

I am using PHP 5.6.14-0+deb8u1 on Debian Jessie. Here is an IMAP debug log:

ov 14 15:41:23 srv01 dovecot: imap-login: Login: user=<test at domain.com>, method=PLAIN, rip=127.0.0.1, lip=127.0.1.1, mpid=20393, TLS, session=<mkWRK4EktAB/AAAB>
Nov 14 15:41:23 srv01 dovecot: imap: Debug: Loading modules from directory: /usr/lib/dovecot/modules
Nov 14 15:41:23 srv01 dovecot: imap: Debug: Module loaded: /usr/lib/dovecot/modules/lib10_quota_plugin.so
Nov 14 15:41:23 srv01 dovecot: imap: Debug: Module loaded: /usr/lib/dovecot/modules/lib11_imap_quota_plugin.so
Nov 14 15:41:23 srv01 dovecot: imap: Debug: Added userdb setting: plugin/quota_rule=*:storage=512M
Nov 14 15:41:23 srv01 dovecot: imap(test at domain.com): Debug: Effective uid=1001, gid=1001, home=/var/vmail/domain.com/test
Nov 14 15:41:23 srv01 dovecot: imap(test at domain.com): Debug: Quota root: name=User quota backend=maildir args=
Nov 14 15:41:23 srv01 dovecot: imap(test at domain.com): Debug: Quota rule: root=User quota mailbox=* bytes=536870912 messages=0
Nov 14 15:41:23 srv01 dovecot: imap(test at domain.com): Debug: Quota warning: bytes=483183820 (90%) messages=0 reverse=no command=quota-warning 90 test at domain.com
Nov 14 15:41:23 srv01 dovecot: imap(test at domain.com): Debug: Quota warning: bytes=375809638 (70%) messages=0 reverse=no command=quota-warning 70 test at domain.com
Nov 14 15:41:23 srv01 dovecot: imap(test at domain.com): Debug: Quota grace: root=User quota bytes=53687091 (10%)
Nov 14 15:41:23 srv01 dovecot: imap(test at domain.com): Debug: Namespace inbox: type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:~/mail:LAYOUT=fs
Nov 14 15:41:23 srv01 dovecot: imap(test at domain.com): Debug: fs: root=/var/vmail/domain.com/test/mail, index=, indexpvt=, control=, inbox=/var/vmail/domain.com/test/mail, alt=
Nov 14 15:41:24 srv01 HORDE: [imp] Login success for test at domain.com (79.204.123.123) to {imap://srv01.domain.com/} [pid 17200 on line 157 of "/var/www/horde/htdocs/horde-base/imp/lib/Auth.php"]
Nov 14 15:41:24 srv01 dovecot: imap(test at domain.com): Disconnected: Logged out in=124 out=686
Nov 14 15:41:24 srv01 dovecot: auth: Debug: auth client connected (pid=20394)
Nov 14 15:41:24 srv01 dovecot: auth: Debug: client in: AUTH#0111#011PLAIN#011service=imap#011secured#011session=dDyZK4EktgB/AAAB#011lip=127.0.1.1#011rip=127.0.0.1#011lport=143#011rport=58294#011resp=<hidden>
Nov 14 15:41:24 srv01 dovecot: auth-worker(20177): Debug: sql(test at domain.com,127.0.0.1): query: SELECT username, domain, password FROM users WHERE username = 'test' AND domain = 'domain.com'
Nov 14 15:41:24 srv01 dovecot: auth: Debug: client passdb out: OK#0111#011user=test at domain.com
Nov 14 15:41:24 srv01 dovecot: auth: Debug: master in: REQUEST#0111336541185#01120394#0111#011a5524e17b6672e2584952e21075f8be3#011session_pid=20395#011request_auth_token
Nov 14 15:41:24 srv01 dovecot: auth-worker(20177): Debug: sql(test at domain.com,127.0.0.1): SELECT concat('*:storage=', mailbox_limit, 'M') AS quota_rule FROM users WHERE username = 'test' AND domain = 'domain.com'
Nov 14 15:41:24 srv01 dovecot: auth: Debug: master userdb out: USER#0111336541185#011test at domain.com#011quota_rule=*:storage=512M#011auth_token=afc01c635bcd417e97bea8a1cf1a40bf199886b5
Nov 14 15:41:24 srv01 dovecot: imap-login: Login: user=<test at domain.com>, method=PLAIN, rip=127.0.0.1, lip=127.0.1.1, mpid=20395, TLS, session=<dDyZK4EktgB/AAAB>
Nov 14 15:41:24 srv01 dovecot: imap: Debug: Loading modules from directory: /usr/lib/dovecot/modules
Nov 14 15:41:24 srv01 dovecot: imap: Debug: Module loaded: /usr/lib/dovecot/modules/lib10_quota_plugin.so
Nov 14 15:41:24 srv01 dovecot: imap: Debug: Module loaded: /usr/lib/dovecot/modules/lib11_imap_quota_plugin.so
Nov 14 15:41:24 srv01 dovecot: imap: Debug: Added userdb setting: plugin/quota_rule=*:storage=512M
Nov 14 15:41:24 srv01 dovecot: imap(test at domain.com): Debug: Effective uid=1001, gid=1001, home=/var/vmail/domain.com/test
Nov 14 15:41:24 srv01 dovecot: imap(test at domain.com): Debug: Quota root: name=User quota backend=maildir args=
Nov 14 15:41:24 srv01 dovecot: imap(test at domain.com): Debug: Quota rule: root=User quota mailbox=* bytes=536870912 messages=0
Nov 14 15:41:24 srv01 dovecot: imap(test at domain.com): Debug: Quota warning: bytes=483183820 (90%) messages=0 reverse=no command=quota-warning 90 test at domain.com
Nov 14 15:41:24 srv01 dovecot: imap(test at domain.com): Debug: Quota warning: bytes=375809638 (70%) messages=0 reverse=no command=quota-warning 70 test at domain.com
Nov 14 15:41:24 srv01 dovecot: imap(test at domain.com): Debug: Quota grace: root=User quota bytes=53687091 (10%)
Nov 14 15:41:24 srv01 dovecot: imap(test at domain.com): Debug: Namespace inbox: type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:~/mail:LAYOUT=fs
Nov 14 15:41:24 srv01 dovecot: imap(test at domain.com): Debug: fs: root=/var/vmail/domain.com/test/mail, index=, indexpvt=, control=, inbox=/var/vmail/domain.com/test/mail, alt=
Nov 14 15:41:24 srv01 HORDE: [imp] Login success for test at domain.com (79.204.123.123) to {imap://srv01.domain.com/} [pid 17201 on line 157 of "/var/www/horde/htdocs/horde-base/imp/lib/Auth.php"]
Nov 14 15:41:24 srv01 dovecot: imap(test at domain.com): Disconnected: Logged out in=124 out=686
Nov 14 15:41:25 srv01 dovecot: auth: Debug: auth client connected (pid=20396)

Thanks!
Lucas

> 
> See https://bugs.horde.org/ticket/13825 for a description of the bug in
> question.
> 
> 
> >
> >
> >> If I have to provide more information, I will do of course.
> >>
> >> Thank you!
> >>
> >> Lucas
> >>
> >> -----Ursprüngliche Nachricht-----
> >> Von: horde [mailto:horde-bounces at lists.horde.org] Im Auftrag von
> >> Torben Dannhauer
> >> Gesendet: Montag, 26. Oktober 2015 20:18
> >> An: horde at lists.horde.org
> >> Betreff: Re: [horde] Problems with Active Sync and Outlook 2013/2016
> >>
> >> I use Horde EAS with Outlook 2016 and it works as expected.
> >>
> >> Regards,
> >> Torben
> >>
> >> -----Ursprüngliche Nachricht-----
> >> Von: horde [mailto:horde-bounces at lists.horde.org] Im Auftrag von
> >> Michael J Rubinsky
> >> Gesendet: Freitag, 23. Oktober 2015 18:07
> >> An: Lucas (wb7) <lg at wb7.eu>
> >> Cc: horde at lists.horde.org
> >> Betreff: Re: [horde] Problems with Active Sync and Outlook 2013/2016
> >>
> >> Please do not top post.
> >>
> >> Quoting Lucas (wb7) <lg at wb7.eu>:
> >>
> >>> Here is a debug log with a few connection tries from OL 2016:
> >>> http://pastebin.com/H8wNXz5q
> >>>
> >>> A wiretrace is not so easy, because of the usage of HTTPS, but I'll
> >>> try my best, if you need one.
> >>
> >> Wireshark allows you to provide it with a copy of the certificate.
> >>
> >>
> >>> Does the debug log show something irregular?
> >>
> >> Unfortunately, no.
> >>
> >> It's very strange. I have no issues with OL2013. Hopefully it's not
> >> something silly that OL2016 is doing. I don't have access to it to
> >> test.
> >>
> >>
> >>> -----Ursprüngliche Nachricht-----
> >>> Von: horde [mailto:horde-bounces at lists.horde.org] Im Auftrag von
> >>> Michael J Rubinsky
> >>> Gesendet: Freitag, 23. Oktober 2015 17:55
> >>> An: horde at lists.horde.org
> >>> Betreff: Re: [horde] Problems with Active Sync and Outlook 2013/2016
> >>>
> >>>
> >>> Quoting Lucas (wb7) <lg at wb7.eu>:
> >>>
> >>>> Hi Michael,
> >>>>
> >>>> thank you for your response. My horde log shows
> >>>>
> >>>> Oct 23 14:42:28 isogramm HORDE: [imp] Login success for
> >>>> test at domain.com (79.204.123.123) to {imap://horde.server.de/} [pid
> >>>> 28966 on line 157 of
> >>>> "/var/www/horde/htdocs/horde-base/imp/lib/Auth.php"]
> >>>>
> >>>> for about 4 times per second if I start outlook and while it trys
> >>>> to sync. Do you need a debug log? The webserver log is clear; there
> >>>> are no errors.
> >>>
> >>> Debug log *might* show something. If you can get a wiretrace,
> >>> though, that would be even better.
> >>>
> >>>
> >>>
> >>> --
> >>> 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
> >>
> >> --
> >> Horde mailing list
> >> Frequently Asked Questions: http://horde.org/faq/ To unsubscribe,
> >> mail: horde-unsubscribe at lists.horde.org
> >>
> >> --
> >> 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
> 
> 
> --
> mike
> The Horde Project
> http://www.horde.org
> https://www.facebook.com/hordeproject
> https://www.twitter.com/hordeproject



More information about the horde mailing list