Re: Question on setting up dovecot
Many thanks to Joseph Tam and Steffen Kaiser for pointing me to the logging information that I should have been using in the first place. Now that I have separate log files and debugging information, I'm still at a loss to see why the mail is not being pulled over from the Linux system to the Windows 7 machine. To recap, here is the output from doveconf -n:
# 2.2.18: /etc/dovecot/dovecot.conf # Pigeonhole version 0.4.8 (0c4ae064f307+) # OS: Linux 4.2.0-27-generic x86_64 Ubuntu 15.10 auth_debug = yes auth_verbose = yes disable_plaintext_auth = no info_log_path = /var/log/dovecot.info log_path = /var/log/dovecot mail_debug = yes mail_location = mbox:~/lib/Mail:INBOX=/var/mail/%u passdb { driver = pam } protocols = imap pop3 ssl_cert =
Here is the configuration logging/debug info:
log_path = /var/log/dovecot info_log_path = /var/log/dovecot.info auth_verbose = yes auth_debug = yes mail_debug = yes verbose_ssl = yes
And here is the complete set of Debug/Info information logged when Outlook on the Win7 system attempts a Send/Receive operation. Long lines broken for readability:
Feb 11 08:47:49 auth: Debug: Loading modules from directory:
/usr/lib/dovecot/modules/auth
Feb 11 08:47:49 auth: Debug: Read auth token secret from
/var/run/dovecot/auth-token-secret.dat
Feb 11 08:47:49 auth: Debug: auth client connected (pid=7861)
Feb 11 08:47:49 auth: Debug: client in: AUTH 1 PLAIN
service=pop3 session=dF2ST4ErtQDMa1sC
lip= rip=
lport=110 rport=54453 resp=<hidden>
Feb 11 08:47:49 auth-worker(7864): Debug: Loading modules from directory:
/usr/lib/dovecot/modules/auth
Feb 11 08:47:49 auth-worker(7864): Debug: pam(sherril,):
lookup service=dovecot
Feb 11 08:47:49 auth-worker(7864): Debug: pam(sherril,):
#1/1 style=1 msg=Password:
Feb 11 08:47:49 auth: Debug: client passdb out: OK 1 user=sherril
Feb 11 08:47:49 auth: Debug: master in: REQUEST 1975517185 7861 1
b48910c85f39498e2fe83277d7e94028 session_pid=7866
Feb 11 08:47:49 auth-worker(7864): Debug: pam(sherril,): lookup
Feb 11 08:47:49 auth: Debug: master userdb out: USER 1975517185
sherril system_groups_user=sherril uid=1001 gid=1001
home=/u/sherril
Feb 11 08:47:49 pop3-login: Info: Login: user=<sherril>, method=PLAIN,
rip=, lip=, mpid=7866,
session=<dF2ST4ErtQDMa1sC>
Feb 11 08:47:49 pop3(sherril): Debug: Effective uid=1001, gid=1001,
home=/u/sherril
Feb 11 08:47:49 pop3(sherril): Debug: fs: root=/u/sherril/lib/Mail,
index=, indexpvt=, control=, inbox=/var/mail/sherril, alt=
Feb 11 08:47:49 pop3(sherril): Info: Disconnected: Logged out top=0/0,
retr=0/0, del=0/3, size=36928
lport=110 rport=54453 resp=<hidden> Feb 11 08:47:49 auth-worker(7864): Debug: Loading modules from directory:
/usr/lib/dovecot/modules/auth Feb 11 08:47:49 auth-worker(7864): Debug: pam(sherril,
lookup service=dovecot Feb 11 08:47:49 auth-worker(7864): Debug: pam(sherril,
#1/1 style=1 msg=Password: Feb 11 08:47:49 auth: Debug: client passdb out: OK 1 user=sherril Feb 11 08:47:49 auth: Debug: master in: REQUEST 1975517185 7861 1
b48910c85f39498e2fe83277d7e94028 session_pid=7866 Feb 11 08:47:49 auth-worker(7864): Debug: pam(sherril,
sherril system_groups_user=sherril uid=1001 gid=1001
home=/u/sherril Feb 11 08:47:49 pop3-login: Info: Login: user=<sherril>, method=PLAIN,
rip=
session=<dF2ST4ErtQDMa1sC> Feb 11 08:47:49 pop3(sherril): Debug: Effective uid=1001, gid=1001,
home=/u/sherril Feb 11 08:47:49 pop3(sherril): Debug: fs: root=/u/sherril/lib/Mail,
index=, indexpvt=, control=, inbox=/var/mail/sherril, alt= Feb 11 08:47:49 pop3(sherril): Info: Disconnected: Logged out top=0/0,
retr=0/0, del=0/3, size=36928
There are no errors. We have a pop3 connection. The user's name, uid, gid, password, home directory are all correct. The inbox is listed as /var/mail/sherril which is correct, and this file has the correct ownership and permissions:
# ls -l /var/mail/sherril -rw-rw---- 1 sherril mail 194900 Feb 11 09:00 /var/mail/sherril
Yet, no mail is pulled over. Are there any clues here?
Thanks,
Jeff
Am 11.02.2016 um 18:09 schrieb Jeffery Small:
Many thanks to Joseph Tam and Steffen Kaiser for pointing me to the logging information that I should have been using in the first place. Now that I have separate log files and debugging information, I'm still at a loss to see why the mail is not being pulled over from the Linux system to the Windows 7 machine. To recap, here is the output from doveconf -n:
# 2.2.18: /etc/dovecot/dovecot.conf # Pigeonhole version 0.4.8 (0c4ae064f307+) # OS: Linux 4.2.0-27-generic x86_64 Ubuntu 15.10 auth_debug = yes auth_verbose = yes disable_plaintext_auth = no info_log_path = /var/log/dovecot.info log_path = /var/log/dovecot mail_debug = yes mail_location = mbox:~/lib/Mail:INBOX=/var/mail/%u Is the mail_location setting correct? I'm not sure about the mbox format an notation. So check the dovecot wiki for further infos. passdb { driver = pam } protocols = imap pop3 ssl_cert =
Here is the configuration logging/debug info:
log_path = /var/log/dovecot info_log_path = /var/log/dovecot.info auth_verbose = yes auth_debug = yes mail_debug = yes verbose_ssl = yes
And here is the complete set of Debug/Info information logged when Outlook on the Win7 system attempts a Send/Receive operation. Long lines broken for readability:
Feb 11 08:47:49 auth: Debug: Loading modules from directory:
/usr/lib/dovecot/modules/auth Feb 11 08:47:49 auth: Debug: Read auth token secret from
/var/run/dovecot/auth-token-secret.dat Feb 11 08:47:49 auth: Debug: auth client connected (pid=7861) Feb 11 08:47:49 auth: Debug: client in: AUTH 1 PLAIN
service=pop3 session=dF2ST4ErtQDMa1sC
lip=rip=
lport=110 rport=54453 resp=<hidden> Feb 11 08:47:49 auth-worker(7864): Debug: Loading modules from directory:
/usr/lib/dovecot/modules/auth Feb 11 08:47:49 auth-worker(7864): Debug: pam(sherril,):
lookup service=dovecot Feb 11 08:47:49 auth-worker(7864): Debug: pam(sherril,):
#1/1 style=1 msg=Password: Feb 11 08:47:49 auth: Debug: client passdb out: OK 1 user=sherril Feb 11 08:47:49 auth: Debug: master in: REQUEST 1975517185 7861 1
b48910c85f39498e2fe83277d7e94028 session_pid=7866 Feb 11 08:47:49 auth-worker(7864): Debug: pam(sherril,): lookup Feb 11 08:47:49 auth: Debug: master userdb out: USER 1975517185
sherril system_groups_user=sherril uid=1001 gid=1001
home=/u/sherril Feb 11 08:47:49 pop3-login: Info: Login: user=<sherril>, method=PLAIN,
rip=, lip= , mpid=7866,
session=<dF2ST4ErtQDMa1sC> Feb 11 08:47:49 pop3(sherril): Debug: Effective uid=1001, gid=1001,
home=/u/sherril Feb 11 08:47:49 pop3(sherril): Debug: fs: root=/u/sherril/lib/Mail,
index=, indexpvt=, control=, inbox=/var/mail/sherril, alt= Check both directories "/u/sherril/lib/Mail", "/var/mail/sherril" for correct ownership and permissions. Feb 11 08:47:49 pop3(sherril): Info: Disconnected: Logged out top=0/0,
retr=0/0, del=0/3, size=36928There are no errors. We have a pop3 connection. The user's name, uid, gid, password, home directory are all correct. The inbox is listed as /var/mail/sherril which is correct, and this file has the correct ownership and permissions:
# ls -l /var/mail/sherril -rw-rw---- 1 sherril mail 194900 Feb 11 09:00 /var/mail/sherril
Are there any mails in that directory? Check if it is possible to walk thought the directory tree. If the user doesn't have the right to read /var , he will never reach /var/mail/sherril.
Yet, no mail is pulled over. Are there any clues here?
Thanks,
Jeff
Philipp
W dniu 11.02.2016 o 18:09, Jeffery Small pisze:
Many thanks to Joseph Tam and Steffen Kaiser for pointing me to the logging information that I should have been using in the first place. Now that I have separate log files and debugging information, I'm still at a loss to see why the mail is not being pulled over from the Linux system to the Windows 7 machine. To recap, here is the output from doveconf -n:
# 2.2.18: /etc/dovecot/dovecot.conf # Pigeonhole version 0.4.8 (0c4ae064f307+) # OS: Linux 4.2.0-27-generic x86_64 Ubuntu 15.10 auth_debug = yes auth_verbose = yes disable_plaintext_auth = no info_log_path = /var/log/dovecot.info log_path = /var/log/dovecot mail_debug = yes mail_location = mbox:~/lib/Mail:INBOX=/var/mail/%u passdb { driver = pam } protocols = imap pop3 ssl_cert =
Here is the configuration logging/debug info:
log_path = /var/log/dovecot info_log_path = /var/log/dovecot.info auth_verbose = yes auth_debug = yes mail_debug = yes verbose_ssl = yes
And here is the complete set of Debug/Info information logged when Outlook on the Win7 system attempts a Send/Receive operation. Long lines broken for readability:
Feb 11 08:47:49 auth: Debug: Loading modules from directory:
/usr/lib/dovecot/modules/auth Feb 11 08:47:49 auth: Debug: Read auth token secret from
/var/run/dovecot/auth-token-secret.dat Feb 11 08:47:49 auth: Debug: auth client connected (pid=7861)cokycody2@gmail.com 10-Feb-16 13:28 # https://www.gurochan.ch/g/res/23522.html#24347 24347 https://www.gurochan.ch/g/res/23522.html#q24347
Feb 11 08:47:49 auth: Debug: client in: AUTH 1 PLAIN
service=pop3 session=dF2ST4ErtQDMa1sC
lip=rip=
lport=110 rport=54453 resp=<hidden> Feb 11 08:47:49 auth-worker(7864): Debug: Loading modules from directory:
/usr/lib/dovecot/modules/auth Feb 11 08:47:49 auth-worker(7864): Debug: pam(sherril,):
lookup service=dovecot Feb 11 08:47:49 auth-worker(7864): Debug: pam(sherril,):
#1/1 style=1 msg=Password: Feb 11 08:47:49 auth: Debug: client passdb out: OK 1 user=sherril Feb 11 08:47:49 auth: Debug: master in: REQUEST 1975517185 7861 1
b48910c85f39498e2fe83277d7e94028 session_pid=7866 Feb 11 08:47:49 auth-worker(7864): Debug: pam(sherril,): lookup Feb 11 08:47:49 auth: Debug: master userdb out: USER 1975517185
sherril system_groups_user=sherril uid=1001 gid=1001
home=/u/sherril Feb 11 08:47:49 pop3-login: Info: Login: user=<sherril>, method=PLAIN,
rip=, lip= , mpid=7866,
session=<dF2ST4ErtQDMa1sC> Feb 11 08:47:49 pop3(sherril): Debug: Effective uid=1001, gid=1001,
home=/u/sherril Feb 11 08:47:49 pop3(sherril): Debug: fs: root=/u/sherril/lib/Mail,
index=, indexpvt=, control=, inbox=/var/mail/sherril, alt= Feb 11 08:47:49 pop3(sherril): Info: Disconnected: Logged out top=0/0,
retr=0/0, del=0/3, size=36928Hi
For me it seems like outlook problem (we sometimes get strange problems with outlook, most of the time they are caused either by AV software interfering or outlook user profile or .pst file corruption) Have you checked different mail program or even telnet to see for problems? As or anti virus programs we had problems i can say for sure Norton and Kaspersky were problematic, causing unexpected connection failures ie after successfull auth connection was closed. As for outlook problems in most cases of pst coruption we got outlook errors suggesting connection problems. With profile corruption there were quite fiew different problems (outlook not cnnecting, droping connection out of bue, having problem authenticating, repetadly asking for password ...) The best way we found to test it was using different emai client to test if the problem is generic or outlook related in rare cases we used telnet (pop3 is quite simple protocol)
As for dovecot side there are some compatibility options for older (hopefully) mail clients outlook related would be pop3_client_workarounds = outlook-no-nuls oe-ns-eoh
As for Your log last line suggests there are 3 mails in inbox
Info: Disconnected: Logged out top=0/0, retr=0/0, del=0/3, size=36928
top=x/y x- top commands issued (comand to retrive part of message ie headers) y - bytes sent as result retr=x/y x - retr commands issued (client requests to get message) the size is cumulative size of all messages del=x/y x - number of deleted messages y- numer of messages in inbox before any deletes, if there were no deletes total messages in inbox size=x - mailbox size in bytes before any messages were deleted
mbox format stores multiple messages in single file You can just du sudo -u sherril cat /var/mail/sherril to see the messages on commandline and at same time check if user sherril has permission to read the file (if im right you are using system users not virual ones) but according to the log everything should be ok.
regards Przemek
There are no errors. We have a pop3 connection. The user's name, uid, gid, password, home directory are all correct. The inbox is listed as /var/mail/sherril which is correct, and this file has the correct ownership and permissions:
# ls -l /var/mail/sherril -rw-rw---- 1 sherril mail 194900 Feb 11 09:00 /var/mail/sherril
Yet, no mail is pulled over. Are there any clues here?
Thanks,
Jeff
-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1
On Thu, 11 Feb 2016, Przemysław Orzechowski wrote:
Feb 11 08:47:49 pop3(sherril): Debug: Effective uid=1001, gid=1001,
home=/u/sherril Feb 11 08:47:49 pop3(sherril): Debug: fs: root=/u/sherril/lib/Mail,
index=, indexpvt=, control=, inbox=/var/mail/sherril, alt= Feb 11 08:47:49 pop3(sherril): Info: Disconnected: Logged out top=0/0,
retr=0/0, del=0/3, size=36928
As for Your log last line suggests there are 3 mails in inbox
Info: Disconnected: Logged out top=0/0, retr=0/0, del=0/3, size=36928
I think Przemysław is right, deliver a new message to /var/mail/sherril and try again. Has Outlook configured to "keep messages on server"? That would make sense to not delete the messages.
# ls -l /var/mail/sherril -rw-rw---- 1 sherril mail 194900 Feb 11 09:00 /var/mail/sherril
Yet, no mail is pulled over. Are there any clues here?
maybe because the MUA thinks they are old? You need a new one.
Steffen Kaiser -----BEGIN PGP SIGNATURE----- Version: GnuPG v1
iQEVAwUBVr2dcnz1H7kL/d9rAQLY3wgAiS5untAczJU9a7Vc/HJLp51eJioztq9U HTcRWCxzVzv0qxHsc6gwQqjKbM13MnPCz62SueeM1NVSgBXH5cIZiDrwENaOJSYy t0QhCJS1xJPhveNPplu/YUVr+8GqC4yRv5+gKKRFg4QFWkZuLLfDh3naOeV1Or22 DJ65qCFPe66h/4B+wTJeWNQWru/Ne97mPWr8Jt6nMhvt17duR0PvpTuJE+bo36ob 2WqLCzLPOXJXcGko6CNDxG1gYAe84AQJRGdbI1zs91/+MyQ2usxlrIj7SmOYPMx0 GIlpJ9Wiu8eMm9O/Zgpf/5VBN6Y8wrNMYXn5wQ/LB36XweqwTKQjFg== =jDAl -----END PGP SIGNATURE-----
On 02/11/16 12:09 PM, Jeffery Small wrote:
# ls -l /var/mail/sherril -rw-rw---- 1 sherril mail 194900 Feb 11 09:00 /var/mail/sherril
Yet, no mail is pulled over. Are there any clues here?
http://wiki.dovecot.org/TestInstallation
Examples using openssl s_client:
openssl s_client -connect server_ip:143 -starttls imap
a login username password b list "" * c select INBOX d fetch 1:* flags e fetch 1:* INTERNALDATE f fetch 1:* (body[header.fields (from to subject date)]) g logout
openssl s_client -connect server_ip:110 -starttls pop3
user username pass password list retr 1 quit
On 02/11/16 12:09 PM, Jeffery Small wrote:
Feb 11 08:47:49 pop3(sherril): Info: Disconnected: Logged out top=0/0,
retr=0/0, del=0/3, size=36928
I think "retr=0/0, del=0/3" means that there are 3 messages but the client (Outlook) did not fetch any.
I'm just getting around to reading all the helpful messages regarding my problem and will be trying out each of the suggestions. A very big thanks to everyone for the supportive help. I'll let you know what I discover.
In the meantime I wanted to mention one additional thing. I am switching mail over from a Solaris server to a newer Linux box and Outlook on this Windows 7 machine has and continues to successfully communicate with the Solaris system, downloading all the mail. I can take the mail file from the Linux /var/mail directory, transfer it over to the Solaris machine, point the Windows machine at that server, and it downloads the mail without a problem, so if Outlook is part of the trouble, it is due to some unique interaction with dovecot. All file permissions and ownership are identical on both servers. So there is some subtle issue here that I just haven't nailed down.
Regards,
Jeff
participants (5)
-
Jeffery Small
-
Oscar del Rio
-
Przemysław Orzechowski
-
Steffen Kaiser
-
Test