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 = </etc/dovecot/dovecot.pem
ssl_key = </etc/dovecot/private/dovecot.pem
userdb {
driver = passwd
}
userdb {
driver = passwd
}
verbose_ssl = yes
----------------------------------------------------------------------
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=<server_IP> rip=<client_IP> \
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,<client_IP>): \
lookup service=dovecot
Feb 11 08:47:49 auth-worker(7864): Debug: pam(sherril,<client_IP>): \
#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,<client_IP>): 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=<client_IP>, lip=<server_IP>, 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, altFeb 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/%uIs 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 = </etc/dovecot/dovecot.pem > ssl_key = </etc/dovecot/private/dovecot.pem > userdb { > driver = passwd > } > userdb { > driver = passwd > } > verbose_ssl = yes > ---------------------------------------------------------------------- > > 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=<server_IP> rip=<client_IP> \ > 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,<client_IP>): \ > lookup service=dovecot > Feb 11 08:47:49 auth-worker(7864): Debug: pam(sherril,<client_IP>): \ > #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,<client_IP>): 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=<client_IP>, lip=<server_IP>, 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, altCheck both directories "/u/sherril/lib/Mail", "/var/mail/sherril" forcorrect ownership and permissions.> 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/sherrilAre 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 = </etc/dovecot/dovecot.pem > ssl_key = </etc/dovecot/private/dovecot.pem > userdb { > driver = passwd > } > userdb { > driver = passwd > } > verbose_ssl = yes > ---------------------------------------------------------------------- > > 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 at 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=<server_IP> rip=<client_IP> \ > 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,<client_IP>): \ > lookup service=dovecot > Feb 11 08:47:49 auth-worker(7864): Debug: pam(sherril,<client_IP>): \ > #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,<client_IP>): 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=<client_IP>, lip=<server_IP>, 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 > ----------------------------------------------------------------------Hi 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 >
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=36928I 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
-----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=36928I 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-----