[Dovecot] Dovecot immediate logout (imap4)
I've experienced with automatic client logout (probably after IDLE command sent by a client).
I simply run any imap4 client and get this in mail.log:
May 14 18:26:37 athlon dovecot: imap-login: Login: user=<neptunix@tehexergo.ru>, method=PLAIN, rip=192.168.0.3, lip=192.168.0.3, secured May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): Loading modules from directory: /usr/lib/dovecot/modules/imap May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): Module loaded: /usr/lib/dovecot/modules/imap/lib10_quota_plugin.so May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): Module loaded: /usr/lib/dovecot/modules/imap/lib11_imap_quota_plugin.so May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): Effective uid=112, gid=118, home=/var/spool/mail/tehexergo.ru/neptunix May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): maildir: data=/var/spool/mail/tehexergo.ru/neptunix/Maildir:INDEX=/var/spool/mail/tehexergo.ru/neptunix May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): maildir: root=/var/spool/mail/tehexergo.ru/neptunix/Maildir, index=/var/spool/mail/tehexergo.ru/neptunix, control=, inbox= May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): Disconnected: Logged out May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): Loading modules from directory: /usr/lib/dovecot/modules/imap May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): Module loaded: /usr/lib/dovecot/modules/imap/lib10_quota_plugin.so May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): Module loaded: /usr/lib/dovecot/modules/imap/lib11_imap_quota_plugin.so May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): Effective uid=112, gid=118, home=/var/spool/mail/tehexergo.ru/neptunix May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): maildir: data=/var/spool/mail/tehexergo.ru/neptunix/Maildir:INDEX=/var/spool/mail/tehexergo.ru/neptunix May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): maildir: root=/var/spool/mail/tehexergo.ru/neptunix/Maildir, index=/var/spool/mail/tehexergo.ru/neptunix, control=, inbox= May 14 18:26:37 athlon dovecot: imap-login: Login: user=<neptunix@tehexergo.ru>, method=PLAIN, rip=192.168.0.3, lip=192.168.0.3, secured May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): Disconnected: Logged out May 14 18:26:37 athlon dovecot: imap-login: Login: user=<neptunix@tehexergo.ru>, method=PLAIN, rip=192.168.0.3, lip=192.168.0.3, secured May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): Loading modules from directory: /usr/lib/dovecot/modules/imap May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): Module loaded: /usr/lib/dovecot/modules/imap/lib10_quota_plugin.so May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): Module loaded: /usr/lib/dovecot/modules/imap/lib11_imap_quota_plugin.so May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): Effective uid=112, gid=118, home=/var/spool/mail/tehexergo.ru/neptunix May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): maildir: data=/var/spool/mail/tehexergo.ru/neptunix/Maildir:INDEX=/var/spool/mail/tehexergo.ru/neptunix May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): maildir: root=/var/spool/mail/tehexergo.ru/neptunix/Maildir, index=/var/spool/mail/tehexergo.ru/neptunix, control=, inbox= May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): Disconnected: Logged out
3 login attempts each after another. Any way client shows me the inbox and I can manage mail just fine. However it disconnects every time instead of using idle command (this I my guess).
Don't know the reason for such a strange situation. Thanks for any help in advance. By the way I can't get rawlog to work. It doesn't write any logs to home directory.
dovecot 10.0.10 (from ubuntu hardy repository)
dovecot -n # 1.0.10: /etc/dovecot/dovecot.conf log_timestamp: %Y-%m-%d %H:%M:%S ssl_cert_file: /etc/ssl/private/exim.pem ssl_key_file: /etc/ssl/private/exim.pem verbose_ssl: yes login_dir: /var/run/dovecot/login login_executable: /usr/lib/dovecot/imap-login login_greeting: Dovecot MUA ready. login_max_processes_count: 32 login_max_connections: 64 max_mail_processes: 256 verbose_proctitle: yes first_valid_uid: 100 mail_privileged_group: mail mail_location: maildir:/var/spool/mail/%d/%n/Maildir:INDEX=/var/spool/mail/%d/%n mail_debug: yes mail_executable: /usr/lib/dovecot/rawlog /usr/lib/dovecot/imap mail_plugins: quota imap_quota auth default: passdb: driver: ldap args: /etc/dovecot/dovecot-ldap.conf userdb: driver: ldap args: /etc/dovecot/dovecot-ldap.conf socket: type: listen master: path: /var/run/dovecot/auth-master mode: 384 user: mail plugin: quota: maildir
-- Dmitry Brigadirov
On May 14, 2008, at 5:38 PM, Дмитрий Бригадиров wrote:
I've experienced with automatic client logout (probably after IDLE command sent by a client).
What client?
May 14 18:26:37 athlon dovecot: IMAP(neptunix@tehexergo.ru): Disconnected: Logged out
"Logged out" means the client issued a LOGOUT command. Seems like your
client just behaves that way.
3 login attempts each after another. Any way client shows me the inbox and I can manage mail just fine. However it disconnects every time instead of using idle command (this I my guess).
Most webmails behave like that.
By the way I can't get rawlog to work. It doesn't write any logs to home directory.
It doesn't write them to home directory. Did you read http://wiki.dovecot.org/Debugging/Rawlog?
2008/5/14 Timo Sirainen <tss@iki.fi> wrote:
What client?
"Logged out" means the client issued a LOGOUT command. Seems like your client just behaves that way.
Most webmails behave like that.
It was roundcube, right. Checked rawlogs - client really issues logout command.
It doesn't write them to home directory. Did you read http://wiki.dovecot.org/Debugging/Rawlog? I did. Created dovecot.rawlog - seems fine now.
Here are two logs from Thunderbird and Apple Mail:
I clicked 5 folders in Thunderbird and got 5 login attempts: May 15 09:42:00 athlon dovecot: imap-login: Login: user=<neptunix@tehexergo.ru>, method=PLAIN, rip=192.168.0.24, lip=192.168.0.3, TLS May 15 09:42:00 athlon dovecot: IMAP(neptunix@tehexergo.ru): Effective uid=112, gid=118, home=/var/spool/mail/tehexergo.ru/neptunix May 15 09:42:00 athlon dovecot: IMAP(neptunix@tehexergo.ru): maildir: data=/var/spool/mail/tehexergo.ru/neptunix/Maildir:INDEX=/var/spool/mail/tehexergo.ru/neptunix May 15 09:42:00 athlon dovecot: IMAP(neptunix@tehexergo.ru): maildir: root=/var/spool/mail/tehexergo.ru/neptunix/Maildir, index=/var/spool/mail/tehexergo.ru/neptunix, control=, inbox= May 15 09:42:02 athlon dovecot: imap-login: Login: user=<neptunix@tehexergo.ru>, method=PLAIN, rip=192.168.0.24, lip=192.168.0.3, TLS May 15 09:42:02 athlon dovecot: IMAP(neptunix@tehexergo.ru): Effective uid=112, gid=118, home=/var/spool/mail/tehexergo.ru/neptunix May 15 09:42:02 athlon dovecot: IMAP(neptunix@tehexergo.ru): maildir: data=/var/spool/mail/tehexergo.ru/neptunix/Maildir:INDEX=/var/spool/mail/tehexergo.ru/neptunix May 15 09:42:02 athlon dovecot: IMAP(neptunix@tehexergo.ru): maildir: root=/var/spool/mail/tehexergo.ru/neptunix/Maildir, index=/var/spool/mail/tehexergo.ru/neptunix, control=, inbox= May 15 09:42:02 athlon dovecot: imap-login: Login: user=<neptunix@tehexergo.ru>, method=PLAIN, rip=192.168.0.24, lip=192.168.0.3, TLS May 15 09:42:02 athlon dovecot: IMAP(neptunix@tehexergo.ru): Effective uid=112, gid=118, home=/var/spool/mail/tehexergo.ru/neptunix May 15 09:42:02 athlon dovecot: IMAP(neptunix@tehexergo.ru): maildir: data=/var/spool/mail/tehexergo.ru/neptunix/Maildir:INDEX=/var/spool/mail/tehexergo.ru/neptunix May 15 09:42:02 athlon dovecot: IMAP(neptunix@tehexergo.ru): maildir: root=/var/spool/mail/tehexergo.ru/neptunix/Maildir, index=/var/spool/mail/tehexergo.ru/neptunix, control=, inbox= May 15 09:42:03 athlon dovecot: imap-login: Login: user=<neptunix@tehexergo.ru>, method=PLAIN, rip=192.168.0.24, lip=192.168.0.3, TLS May 15 09:42:03 athlon dovecot: IMAP(neptunix@tehexergo.ru): Effective uid=112, gid=118, home=/var/spool/mail/tehexergo.ru/neptunix May 15 09:42:03 athlon dovecot: IMAP(neptunix@tehexergo.ru): maildir: data=/var/spool/mail/tehexergo.ru/neptunix/Maildir:INDEX=/var/spool/mail/tehexergo.ru/neptunix May 15 09:42:03 athlon dovecot: IMAP(neptunix@tehexergo.ru): maildir: root=/var/spool/mail/tehexergo.ru/neptunix/Maildir, index=/var/spool/mail/tehexergo.ru/neptunix, control=, inbox= May 15 09:42:03 athlon dovecot: imap-login: Login: user=<neptunix@tehexergo.ru>, method=PLAIN, rip=192.168.0.24, lip=192.168.0.3, TLS May 15 09:42:03 athlon dovecot: IMAP(neptunix@tehexergo.ru): Effective uid=112, gid=118, home=/var/spool/mail/tehexergo.ru/neptunix May 15 09:42:03 athlon dovecot: IMAP(neptunix@tehexergo.ru): maildir: data=/var/spool/mail/tehexergo.ru/neptunix/Maildir:INDEX=/var/spool/mail/tehexergo.ru/neptunix May 15 09:42:03 athlon dovecot: IMAP(neptunix@tehexergo.ru): maildir: root=/var/spool/mail/tehexergo.ru/neptunix/Maildir, index=/var/spool/mail/tehexergo.ru/neptunix, control=, inbox= May 15 09:42:05 athlon dovecot: IMAP(neptunix@tehexergo.ru): Disconnected in IDLE
Same picture with Apple Mail.
This creates 10 rawlog files (5 ins + 5 outs), each ending with IDLE command. (I've attached these files just in case). Is it nornal, that client makes 5 connections to the IMAP server?
Dmitry Brigadirov
On Thu, 2008-05-15 at 09:49 +0400, Дмитрий Бригадиров wrote:
I clicked 5 folders in Thunderbird and got 5 login attempts: .. This creates 10 rawlog files (5 ins + 5 outs), each ending with IDLE command. (I've attached these files just in case). Is it nornal, that client makes 5 connections to the IMAP server?
Yes, one for each mailbox. IIRC Thunderbird by default uses max. 5 connections.
participants (3)
-
Charles Marcus
-
Timo Sirainen
-
Дмитрий Бригадиров