[Dovecot] Few problem with pop3
Hi, i've upgraded from 1.0.5 to 1.1.2 on 2 server and work fine for user that grab email on client, but some customer (that leave the email on server) have 2 problems.
Some client can't read new mail: dovecot: Aug 19 11:00:46 Info: pop3-login: Login: user=<agenzia>, method=PLAIN, rip=xxx, lip=91.102.48.12 dovecot: Aug 19 11:00:46 Info: POP3(agenzia): Disconnected: Logged out top=0/0, retr=0/0, del=0/21, size=1200006 dovecot: Aug 19 11:01:22 Info: pop3-login: Login: user=<agenzia>, method=PLAIN, rip=xxx, lip=91.102.48.12 dovecot: Aug 19 11:01:23 Info: POP3(agenzia): Disconnected: Logged out top=0/0, retr=0/0, del=0/22, size=1219860 dovecot: Aug 19 11:04:21 Info: pop3-login: Login: user=<agenzia>, method=PLAIN, rip=xxx, lip=91.102.48.12 dovecot: Aug 19 11:04:22 Info: POP3(agenzia): Disconnected: Logged out top=0/0, retr=0/0, del=0/25, size=1277510
other client reread old email dovecot: Aug 18 13:09:30 Info: POP3(soggiorno): Disconnected: Logged out top=0/0, retr=1/9885, del=0/28, size=270604 dovecot: Aug 18 13:21:15 Info: POP3(soggiorno): Disconnected: Logged out top=0/0, retr=1/9885, del=0/28, size=270604 dovecot: Aug 18 16:06:10 Info: POP3(soggiorno): Disconnected: Logged out top=0/0, retr=1/1218, del=0/28, size=270604 dovecot: Aug 18 16:09:08 Info: POP3(soggiorno): Disconnected: Logged out top=0/0, retr=1/1218, del=0/28, size=270604 dovecot: Aug 18 17:09:49 Info: POP3(soggiorno): Disconnected: Logged out top=0/0, retr=1/2479, del=0/29, size=298926 dovecot: Aug 18 17:12:18 Info: POP3(soggiorno): Disconnected: Logged out top=0/0, retr=2/2513, del=0/29, size=298926 dovecot: Aug 18 17:32:59 Info: POP3(soggiorno): Disconnected: Logged out top=0/0, retr=1/1321, del=0/29, size=298926 dovecot: Aug 18 17:58:46 Info: POP3(soggiorno): Disconnected: Logged out top=0/0, retr=1/1321, del=0/29, size=298926
Dovecot on linux, ext3 fs (the 2 server is similar, one with real users and one with virtual users on pgsql), only pop3 (with mbox file)
root@mx:~# dovecot -n # 1.1.2: /usr/local/etc/dovecot.conf base_dir: /var/run/dovecot/ log_path: /var/log/poplog info_log_path: /var/log/poplog protocols: pop3 ssl_disable: yes disable_plaintext_auth: no login_dir: /var/run/dovecot/login login_executable: /usr/local/libexec/dovecot/pop3-login login_processes_count: 4 verbose_proctitle: yes mail_access_groups: mail mail_location: mbox:/var/empty:INBOX=/var/spool/mail/%u:INDEX=MEMORY mail_executable: /usr/local/libexec/dovecot/pop3 mail_plugin_dir: /usr/local/lib/dovecot/pop3 pop3_client_workarounds: oe-ns-eoh outlook-no-nuls auth default: mechanisms: plain login verbose: yes passdb: driver: sql args: /usr/local/etc/dovecot-sql.conf userdb: driver: sql args: /usr/local/etc/dovecot-sql.conf socket: type: listen client: path: /var/spool/postfix/private/auth mode: 432 user: postfix group: postdrop
root@smtp:~# dovecot -n # 1.1.2: /usr/local/etc/dovecot.conf base_dir: /var/run/dovecot/ log_path: /var/log/poplog info_log_path: /var/log/poplog protocols: pop3 ssl_disable: yes disable_plaintext_auth: no login_dir: /var/run/dovecot/login login_executable: /usr/local/libexec/dovecot/pop3-login login_processes_count: 4 verbose_proctitle: yes mail_access_groups: mail mail_location: mbox:/var/empty:INBOX=/var/spool/mail/%u:INDEX=MEMORY mail_executable: /usr/local/libexec/dovecot/pop3 mail_plugin_dir: /usr/local/lib/dovecot/pop3 pop3_client_workarounds: oe-ns-eoh outlook-no-nuls auth default: mechanisms: plain login verbose: yes passdb: driver: shadow userdb: driver: passwd socket: type: listen client: path: /var/spool/postfix/private/auth mode: 432 user: postfix group: postdrop
-- Roberto Tagliaferri Responsabile Progettazione & Produzione TosNet s.r.l. - Internet Service Provider r.tagliaferri@tosnet.it www.tosnet.it
Hi, i've upgraded from 1.0.5 to 1.1.2 on 2 server and work fine for user that grab email on client, but some customer (that leave the email on server) have 2 problems. [...] Update: i've downgraded to 1.0.9 and work fine (1.0.15 has the same
Roberto Tagliaferri - Tosnet srl ha scritto: problem of 1.1.2)
-- Roberto Tagliaferri Responsabile Progettazione & Produzione TosNet s.r.l. - Internet Service Provider r.tagliaferri@tosnet.it www.tosnet.it
On Wed, 2008-08-20 at 07:35 +0200, Roberto Tagliaferri - Tosnet srl wrote:
Hi, i've upgraded from 1.0.5 to 1.1.2 on 2 server and work fine for user that grab email on client, but some customer (that leave the email on server) have 2 problems.
Some client can't read new mail: dovecot: Aug 19 11:00:46 Info: pop3-login: Login: user=<agenzia>, method=PLAIN, rip=xxx, lip=91.102.48.12 dovecot: Aug 19 11:00:46 Info: POP3(agenzia): Disconnected: Logged out top=0/0, retr=0/0, del=0/21, size=1200006
All of these look like normal client QUITs, so I've no idea why the client wouldn't see the new mails. If there's nothing in error logs either (may be different from where the above messages are logged, http://wiki.dovecot.org/Logging), the only possibility to debug this is to look at what the client and Dovecot actually talk to each others and how it differs from v1.0. Rawlog for example could be used for that: http://wiki.dovecot.org/Debugging/Rawlog
Update: i've downgraded to 1.0.9 and work fine (1.0.15 has the same problem of 1.1.2)
There shouldn't be much of a difference between 1.0.9 and 1.0.15..
Timo Sirainen ha scritto:
On Wed, 2008-08-20 at 07:35 +0200, Roberto Tagliaferri - Tosnet srl wrote:
Hi, i've upgraded from 1.0.5 to 1.1.2 on 2 server and work fine for user that grab email on client, but some customer (that leave the email on server) have 2 problems.
Some client can't read new mail: dovecot: Aug 19 11:00:46 Info: pop3-login: Login: user=<agenzia>, method=PLAIN, rip=xxx, lip=91.102.48.12 dovecot: Aug 19 11:00:46 Info: POP3(agenzia): Disconnected: Logged out top=0/0, retr=0/0, del=0/21, size=1200006
All of these look like normal client QUITs, so I've no idea why the client wouldn't see the new mails. If there's nothing in error logs either (may be different from where the above messages are logged, http://wiki.dovecot.org/Logging), the only possibility to debug this is to look at what the client and Dovecot actually talk to each others and how it differs from v1.0. Rawlog for example could be used for that: http://wiki.dovecot.org/Debugging/Rawlog
Ok.. i've tested only one account because this server is a production server.. I've recreated this: dovecot: Aug 21 08:38:54 Info: pop3-login: Login: user=<agenzia>, method=PLAIN, rip=213.203.182.90, lip=91.102.48.12 dovecot: Aug 21 08:38:54 Info: POP3(agenzia): Disconnected: Logged out top=0/0, retr=0/0, del=0/21, size=217625 in this check the client have not download the message
dovecot: Aug 21 08:40:20 Info: pop3-login: Login: user=<agenzia>, method=PLAIN, rip=213.203.182.90, lip=91.102.48.12 dovecot: Aug 21 08:40:20 Info: POP3(agenzia): Disconnected: Logged out top=0/0, retr=1/2962, del=0/21, size=217625 In this check yes... (thunderbird 2.0.16)
For first check, in log: STAT LIST UIDL QUIT
Out log: +OK 21 217625 +OK 21 messages: 1 3849 2 3845 3 4872 4 106997 5 11948 6 3624 7 29799 8 4868 9 4845 10 2525 11 3278 12 3103 13 4671 14 2487 15 2671 16 2694 17 3072 18 5224 19 8862 20 1447 21 2944 . +OK 1 0000e103435e253a 2 0000e104435e253a 3 0000e11d435e253a 4 0000e12b435e253a 5 0000e13e435e253a 6 0000e13f435e253a 7 0000e186435e253a 8 0000e187435e253a 9 0000e188435e253a 10 0000e189435e253a 11 0000e18a435e253a 12 0000e18b435e253a 13 0000e18c435e253a 14 0000e18d435e253a 15 0000e18e435e253a 16 0000e18f435e253a 17 0000e190435e253a 18 0000e191435e253a 19 0000e192435e253a 20 0000e193435e253a 21 0000e194435e253a . +OK Logging out.
For second check, in log:
STAT LIST UIDL RETR 21 QUIT
out log:
+OK 21 217625 +OK 21 messages: 1 3849 2 3845 3 4872 4 106997 5 11948 6 3624 7 29799 8 4868 9 4845 10 2525 11 3278 12 3103 13 4671 14 2487 15 2671 16 2694 17 3072 18 5224 19 8862 20 1447 21 2944 . +OK 1 0000e103435e253a 2 0000e104435e253a 3 0000e11d435e253a 4 0000e12b435e253a 5 0000e13e435e253a 6 0000e13f435e253a 7 0000e186435e253a 8 0000e187435e253a 9 0000e188435e253a 10 0000e189435e253a 11 0000e18a435e253a 12 0000e18b435e253a 13 0000e18c435e253a 14 0000e18d435e253a 15 0000e18e435e253a 16 0000e18f435e253a 17 0000e190435e253a 18 0000e191435e253a 19 0000e192435e253a 20 0000e193435e253a 21 0000e194435e253a . +OK 2944 octets
(cut body of message) +OK Logging out.
Update: i've downgraded to 1.0.9 and work fine (1.0.15 has the same problem of 1.1.2)
There shouldn't be much of a difference between 1.0.9 and 1.0.15..
But when i've reinstalled 1.0.9 dovecot work fine :(
-- Roberto Tagliaferri Responsabile Progettazione & Produzione TosNet s.r.l. - Internet Service Provider r.tagliaferri@tosnet.it www.tosnet.it
On Thu, 2008-08-21 at 09:14 +0200, Roberto Tagliaferri - Tosnet srl wrote:
All of these look like normal client QUITs, so I've no idea why the client wouldn't see the new mails. If there's nothing in error logs either (may be different from where the above messages are logged, http://wiki.dovecot.org/Logging), the only possibility to debug this is to look at what the client and Dovecot actually talk to each others and how it differs from v1.0. Rawlog for example could be used for that: http://wiki.dovecot.org/Debugging/Rawlog
Ok.. i've tested only one account because this server is a production server.. I've recreated this: dovecot: Aug 21 08:38:54 Info: pop3-login: Login: user=<agenzia>, method=PLAIN, rip=213.203.182.90, lip=91.102.48.12 dovecot: Aug 21 08:38:54 Info: POP3(agenzia): Disconnected: Logged out top=0/0, retr=0/0, del=0/21, size=217625 in this check the client have not download the message
dovecot: Aug 21 08:40:20 Info: pop3-login: Login: user=<agenzia>, method=PLAIN, rip=213.203.182.90, lip=91.102.48.12 dovecot: Aug 21 08:40:20 Info: POP3(agenzia): Disconnected: Logged out top=0/0, retr=1/2962, del=0/21, size=217625 In this check yes... (thunderbird 2.0.16)
Were both of these using the same Dovecot version or was the second one different?
For first check, in log: STAT LIST UIDL
In both of the cases Dovecot's replies to these 3 commands were identical. So why did the client decide to do RETR only in the second session?.. I don't really have any ideas what could be the problem.
If you can easily test this, I guess the best way would be to find one which Dovecot v1.0.x version exactly broke this for you (and after that which hg commit change broke it).
Timo Sirainen ha scritto:
If you can easily test this, I guess the best way would be to find one which Dovecot v1.0.x version exactly broke this for you (and after that which hg commit change broke it).
1.0.12 work fine (for now..), i can't try .13 & .14: i've an altered customer at phone when i try other version...
-- Roberto Tagliaferri Responsabile Progettazione & Produzione TosNet s.r.l. - Internet Service Provider r.tagliaferri@tosnet.it www.tosnet.it
participants (2)
-
Roberto Tagliaferri - Tosnet srl
-
Timo Sirainen