November 2009 - dovecot

[Dovecot] Post login script issue with pop3 protocol
by Zhang Huangbin 05 Nov '09

05 Nov '09

Hi, all. I want to track user last login data (ip address, login date, etc) with dovecot, followed dovecot tutorial[1], and it now works with IMAP, but failed with POP3. i don't know why. My script: ---- LDAP_URI='ldap://127.0.0.1:389' LDAP_BASEDN='o=domains,dc=iredmail,dc=org' BIND_DN='cn=vmailadmin,dc=iredmail,dc=org' BIND_PW='plain_passwd' if [ X"${USER}" != X"dump-capability" ]; then ldapmodify -c -x \ -H "${LDAP_URI}" \ -D "${BIND_DN}" \ -w "${BIND_PW}" <<EOF dn: mail=${USER},ou=Users,domainName=$(echo ${USER} | awk -F'@' '{print $2}'),${LDAP_BASEDN} changetype: modify replace: lastLoginDate lastLoginDate: $(date +%Y%m%d%H%M%SZ) - replace: lastLoginIP lastLoginIP: ${IP} - replace: lastLoginProtocol lastLoginProtocol: pop3 EOF fi ---- It works with IMAP protocol, but failed with POP3 protocol. Error msg in Thunderbird: ---- The STAT command did not succeed. Error getting message number and sizes. Mail server r6.iredmail.org responsed: ying entry "mail=www(a)a.cn,ou=Users,domainName=a.cn,o=domains,dc=iredmail,dc=org" ---- [1] Post-login scripting: http://wiki.dovecot.org/PostLoginScripting -- Best Regards. Zhang Huangbin - Open Source Mail Server Solution for Red Hat(R) Enterprise Linux, CentOS, Debian, Ubuntu: http://www.iredmail.org/

4 6

Re: [Dovecot] Dovecot lock problem
by Simon Gao 04 Nov '09

04 Nov '09

> >What is the process doing? Check with "strace -p <imap process pid>"? > >How large is the user's mailbox? We just have another user reporting problem. There is no lock on /var/spool/mail/<user> file. The strace has following: # strace -p 8117 Process 8117 attached - interrupt to quit fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362101 alarm(5) = 0 time(NULL) = 1257362101 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362106 alarm(5) = 0 time(NULL) = 1257362106 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362111 alarm(5) = 0 time(NULL) = 1257362111 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362116 alarm(5) = 0 time(NULL) = 1257362116 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362121 alarm(5) = 0 time(NULL) = 1257362121 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362126 alarm(5) = 0 time(NULL) = 1257362126 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362131 alarm(5) = 0 time(NULL) = 1257362131 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362136 alarm(5) = 0 time(NULL) = 1257362136 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362141 alarm(5) = 0 time(NULL) = 1257362141 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362146 alarm(5) = 0 time(NULL) = 1257362146 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0} # strace -p 8117 Process 8117 attached - interrupt to quit fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362101 alarm(5) = 0 time(NULL) = 1257362101 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362106 alarm(5) = 0 time(NULL) = 1257362106 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362111 alarm(5) = 0 time(NULL) = 1257362111 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362116 alarm(5) = 0 time(NULL) = 1257362116 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362121 alarm(5) = 0 time(NULL) = 1257362121 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362126 alarm(5) = 0 time(NULL) = 1257362126 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362131 alarm(5) = 0 time(NULL) = 1257362131 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362136 alarm(5) = 0 time(NULL) = 1257362136 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362141 alarm(5) = 0 time(NULL) = 1257362141 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362146 alarm(5) = 0 time(NULL) = 1257362146 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362151 alarm(5) = 0 time(NULL) = 1257362151 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362156 alarm(5) = 0 time(NULL) = 1257362156 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362161 alarm(5) = 0 time(NULL) = 1257362161 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362166 alarm(5) = 0 time(NULL) = 1257362166 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362171 alarm(5) = 0 time(NULL) = 1257362171 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362176 alarm(5) = 0 time(NULL) = 1257362176 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}, 0xff9f4db4) = ? ERESTARTSYS (To be restarted) --- SIGALRM (Alarm clock) @ 0 (0) --- sigreturn() = ? (mask now []) time(NULL) = 1257362181 alarm(5) = 0 time(NULL) = 1257362181 fcntl64(8, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0} I did see somewhere log says "no lock file found, abort, etc". Simon

1 0

[Dovecot] quota ignore sent folder
by juan carlos 04 Nov '09

04 Nov '09

I have configured postfix and dovecot and I want to know hot to configure quota in order to ignore sent folder. Now it does work ignoring trash folder but I want to ignore sent.

2 7

[Dovecot] login BAD Error in IMAP command received by server.
by Yaron B 04 Nov '09

04 Nov '09

hi, i can open telnet on port 143 but failing on login user/pass and getting - "login BAD Error in IMAP command received by server." i assume that i didnt setup properly the authentication method and user/pass does Dovecot can use postfix user/pass ? less /var/mail/maillog Nov 3 11:21:45 localhost dovecot: imap-login: Login: user=<info>, method=PLAIN, rip=::1, lip=::1, secured Nov 3 11:21:45 localhost dovecot: IMAP(info): Connection closed bytes=41/347 [root@fedora ~]# dovecot --version 1.2.6 [root@fedora ~]# dovecot -n # 1.2.6: /etc/dovecot.conf # OS: Linux 2.6.30.9-90.fc11.i586 i686 Fedora release 11 (Leonidas) ext4 protocols: imap pop3 login_dir: /var/run/dovecot/login login_executable(default): /usr/libexec/dovecot/imap-login login_executable(imap): /usr/libexec/dovecot/imap-login login_executable(pop3): /usr/libexec/dovecot/pop3-login mail_privileged_group: mail mail_location: mbox:/var/mail/%u maildir_very_dirty_syncs: yes mail_executable(default): /usr/libexec/dovecot/imap mail_executable(imap): /usr/libexec/dovecot/imap mail_executable(pop3): /usr/libexec/dovecot/pop3 mail_plugin_dir(default): /usr/lib/dovecot/imap mail_plugin_dir(imap): /usr/lib/dovecot/imap mail_plugin_dir(pop3): /usr/lib/dovecot/pop3 imap_client_workarounds(default): imap_client_workarounds(imap): imap_client_workarounds(pop3): delay-newmail outlook-idle netscape-eoh pop3_client_workarounds(default): pop3_client_workarounds(imap): pop3_client_workarounds(pop3): outlook-no-nuls oe-ns-eoh lda: postmaster_address: postmaster(a)example.com auth default: passdb: driver: pam userdb: driver: passwd

3 3

[Dovecot] Why : dovecot_destination_recipient_limit = 1 ?
by Koenraad Lelong 03 Nov '09

03 Nov '09

Hi all, I'm trying to activate sieve. In all tutorials I have seen I need to set dovecot_destination_recipient_limit = 1 in Postfix's main.cf. Can anyone explain why ? I like to know because when I set this and the dovecot transport in Postfix, I'm having problems when aliasses are used that map to different recipients, i.e. info@... results in user1@..., user2@..., etc. The problems are an error from Postfix : Oct 30 09:25:19 lace3 postfix/pipe[19729]: 8A77E2D5B5B: to=<some.body(a)some.where.org>, relay=dovecot, delay=2390, delays=2390/0.04/0/0.01, dsn=4.3.5, status=deferred (mail system configuration error) Oct 30 09:25:19 lace3 postfix/pipe[19729]: warning: pipe flag `D' requires dovecot_destination_recipient_limit = 1 Thanks for any info. Regards, Koenraad Lelong.

5 6

[Dovecot] X.509 certificate based IMAP login
by dovecotlist＠encambio.com 03 Nov '09

03 Nov '09

Hello list, The dovecot version is 1.2.6 running on Solaris x86 11 (nv-b91). The relevant configuration lines are: passdb ldap { # LDAP database (doc/wiki/AuthDatabase.LDAP.txt.) args = /pfx/etc/dovecot/dovecot-ldap.conf } The file dovecot-ldap.conf is correct and LDAP authentication is working well. We would like to make it possible for users with a X.509 client certificate to log in without providing LDAP or any other credentials. Is there something like: passdb x509 { args = /pfx/etc/dovecot/dovecot-caroots.pem nopwd = yes } ...avaibable, or is there another solution? Thanks, Brian

2 2

[Dovecot] something similar to last login but for aliases
by Bradley Giesbrecht 03 Nov '09

03 Nov '09

I'm using dovecot deliver with postfix. Is there a place to hook in a db update when an alias is hit? I'm wanting to delete aliases that haven't been used in x time. Thank you, Brad

2 1

[Dovecot] second fs quota root crashes
by Juergen Obermann 03 Nov '09

03 Nov '09

Hallo. In our dovecot 1.2.6 server running under Solaris 9 I would like to set up a second quota root because the mail folders are in the users home directories and the inboxes of all users are in /var/mail. As soon as I add quota2 = fs:INBOX:noenforcing:mount=/var/mail the imap client crashes repeatedly as soon as the user tries to open a mail folder. Thanks for any help. Dovecot Logfile: ================ Oct 20 14:12:49 horus dovecot: [ID 583609 local0.info] Dovecot v1.2.6 starting up Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8214 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8216 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8217 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8218 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8219 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8220 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8222 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8225 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8223 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8230 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8228 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8229 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8224 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8221 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8233 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8231 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8226 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8237 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8236 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8232 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8234 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8235 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8238 Oct 20 14:12:50 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8227 Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] auth(default): client in: AUTH 1 PLAIN service=imap secured lip=134.176.2.11 rip=134.176.150.104 lport=993 rport=1397 Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] auth(default): client out: CONT 1 Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] auth(default): client in: CONT 1 AGcwMzMAcmVkIHNreQ== Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] auth-worker(default): pam(g033,134.176.150.104): lookup service=dovecot Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] auth-worker(default): pam(g033,134.176.150.104): #1/1 style=1 msg=Password: Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] auth(default): client out: OK 1 user=g033 Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] auth(default): master in: REQUEST 1 8236 1 Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] auth(default): passwd(g033,134.176.150.104): lookup Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] auth(default): master out: USER 1 g033 system_groups_user=g033 uid=10033 gid=10010 home=/home/hrz/g033 Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] imap-login: Login: user=<g033>, method=PLAIN, rip=134.176.150.104, lip=134.176.2.11, TLS Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8236 Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] IMAP(g033): Loading modules from directory: /opt/local/lib/dovecot/imap Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] IMAP(g033): Module loaded: /opt/local/lib/dovecot/imap/lib10_quota_plugin.so Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] IMAP(g033): Module loaded: /opt/local/lib/dovecot/imap/lib11_imap_quota_plugin.so Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] IMAP(g033): Effective uid=10033, gid=10010, home=/home/hrz/g033 Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] IMAP(g033): Quota root: name=Home-Verzeichnis backend=fs args=noenforcing Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] IMAP(g033): Quota root: name=INBOX backend=fs args=noenforcing:mount=/var/mail Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] IMAP(g033): Namespace: type=private, prefix=, sep=/, inbox=yes, hidden=no, list=yes, subscriptions=yes Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] IMAP(g033): mbox: data=~/Mail:INBOX=/var/mail/g033 Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] IMAP(g033): fs: root=/home/hrz/g033/Mail, index=, control=, inbox=/var/mail/g033 Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] IMAP(g033): fs quota add storage dir = /home/hrz/g033/Mail Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] IMAP(g033): fs quota block device = fileserv:/export/home1/hrz Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] IMAP(g033): fs quota mount point = /home/hrz Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] IMAP(g033): Namespace: type=private, prefix=Mail/, sep=/, inbox=no, hidden=yes, list=no, subscriptions=yes Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] IMAP(g033): mbox: data=~/Mail:INBOX=/var/mail/g033 Oct 20 14:13:00 horus dovecot: [ID 583609 local0.info] IMAP(g033): fs: root=/home/hrz/g033/Mail, index=, control=, inbox=/var/mail/g033 Oct 20 14:13:01 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8236 Oct 20 14:13:01 horus dovecot: [ID 583609 local0.error] dovecot: child 8241 (imap) killed with signal 11 (core dumped) Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth(default): client in: AUTH 1 PLAIN service=imap secured lip=134.176.2.11 rip=134.176.150.104 lport=993 rport=1398 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth(default): client out: CONT 1 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth(default): client in: CONT 1 AGcwMzMAcmVkIHNreQ== Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth-worker(default): pam(g033,134.176.150.104): lookup service=dovecot Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth-worker(default): pam(g033,134.176.150.104): #1/1 style=1 msg=Password: Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth(default): client out: OK 1 user=g033 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth(default): master in: REQUEST 2 8227 1 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth(default): passwd(g033,134.176.150.104): lookup Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth(default): master out: USER 2 g033 system_groups_user=g033 uid=10033 gid=10010 home=/home/hrz/g033 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] imap-login: Login: user=<g033>, method=PLAIN, rip=134.176.150.104, lip=134.176.2.11, TLS Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8227 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): Loading modules from directory: /opt/local/lib/dovecot/imap Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): Module loaded: /opt/local/lib/dovecot/imap/lib10_quota_plugin.so Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): Module loaded: /opt/local/lib/dovecot/imap/lib11_imap_quota_plugin.so Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): Effective uid=10033, gid=10010, home=/home/hrz/g033 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): Quota root: name=Home-Verzeichnis backend=fs args=noenforcing Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): Quota root: name=INBOX backend=fs args=noenforcing:mount=/var/mail Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): Namespace: type=private, prefix=, sep=/, inbox=yes, hidden=no, list=yes, subscriptions=yes Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): mbox: data=~/Mail:INBOX=/var/mail/g033 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): fs: root=/home/hrz/g033/Mail, index=, control=, inbox=/var/mail/g033 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): fs quota add storage dir = /home/hrz/g033/Mail Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): fs quota block device = fileserv:/export/home1/hrz Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): fs quota mount point = /home/hrz Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): Namespace: type=private, prefix=Mail/, sep=/, inbox=no, hidden=yes, list=no, subscriptions=yes Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): mbox: data=~/Mail:INBOX=/var/mail/g033 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): fs: root=/home/hrz/g033/Mail, index=, control=, inbox=/var/mail/g033 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8227 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.error] dovecot: child 8242 (imap) killed with signal 11 (core dumped) Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth(default): client in: AUTH 2 PLAIN service=imap secured lip=134.176.2.11 rip=134.176.150.104 lport=993 rport=1399 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth(default): client out: CONT 2 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth(default): client in: CONT 2 AGcwMzMAcmVkIHNreQ== Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth-worker(default): pam(g033,134.176.150.104): lookup service=dovecot Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth-worker(default): pam(g033,134.176.150.104): #1/1 style=1 msg=Password: Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth(default): client out: OK 2 user=g033 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth(default): master in: REQUEST 3 8227 2 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth(default): passwd(g033,134.176.150.104): lookup Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth(default): master out: USER 3 g033 system_groups_user=g033 uid=10033 gid=10010 home=/home/hrz/g033 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] imap-login: Login: user=<g033>, method=PLAIN, rip=134.176.150.104, lip=134.176.2.11, TLS Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8227 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): Loading modules from directory: /opt/local/lib/dovecot/imap Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): Module loaded: /opt/local/lib/dovecot/imap/lib10_quota_plugin.so Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): Module loaded: /opt/local/lib/dovecot/imap/lib11_imap_quota_plugin.so Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): Effective uid=10033, gid=10010, home=/home/hrz/g033 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): Quota root: name=Home-Verzeichnis backend=fs args=noenforcing Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): Quota root: name=INBOX backend=fs args=noenforcing:mount=/var/mail Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): Namespace: type=private, prefix=, sep=/, inbox=yes, hidden=no, list=yes, subscriptions=yes Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): mbox: data=~/Mail:INBOX=/var/mail/g033 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): fs: root=/home/hrz/g033/Mail, index=, control=, inbox=/var/mail/g033 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): fs quota add storage dir = /home/hrz/g033/Mail Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): fs quota block device = fileserv:/export/home1/hrz Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): fs quota mount point = /home/hrz Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): Namespace: type=private, prefix=Mail/, sep=/, inbox=no, hidden=yes, list=no, subscriptions=yes Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): mbox: data=~/Mail:INBOX=/var/mail/g033 Oct 20 14:13:02 horus dovecot: [ID 583609 local0.info] IMAP(g033): fs: root=/home/hrz/g033/Mail, index=, control=, inbox=/var/mail/g033 Oct 20 14:13:03 horus dovecot: [ID 583609 local0.info] auth(default): new auth connection: pid=8227 Oct 20 14:13:03 horus dovecot: [ID 583609 local0.error] dovecot: child 8243 (imap) killed with signal 11 (core dumped) Core file backtrace: ==================== horus{g033}1053:>gdb /opt/local/libexec/dovecot/imap core GNU gdb 5.3 Copyright 2002 Free Software Foundation, Inc. GDB is free software, covered by the GNU General Public License, and you are welcome to change it and/or distribute copies of it under certain conditions. Type "show copying" to see the conditions. There is absolutely no warranty for GDB. Type "show warranty" for details. This GDB was configured as "sparc-sun-solaris2.8"... Core was generated by `imap'. Program terminated with signal 11, Segmentation fault. Reading symbols from /usr/local/lib/libiconv.so.2...done. Loaded symbols for /usr/local/lib/libiconv.so.2 Reading symbols from /usr/lib/libdl.so.1...done. Loaded symbols for /usr/lib/libdl.so.1 Reading symbols from /usr/lib/libnsl.so.1...done. Loaded symbols for /usr/lib/libnsl.so.1 Reading symbols from /usr/lib/libsocket.so.1...done. Loaded symbols for /usr/lib/libsocket.so.1 Reading symbols from /usr/lib/librt.so.1...done. Loaded symbols for /usr/lib/librt.so.1 Reading symbols from /usr/lib/libsendfile.so.1...done. Loaded symbols for /usr/lib/libsendfile.so.1 Reading symbols from /usr/lib/libc.so.1...done. Loaded symbols for /usr/lib/libc.so.1 Reading symbols from /usr/lib/libmp.so.2...done. Loaded symbols for /usr/lib/libmp.so.2 Reading symbols from /usr/lib/libaio.so.1...done. Loaded symbols for /usr/lib/libaio.so.1 Reading symbols from /usr/lib/libmd5.so.1...done. Loaded symbols for /usr/lib/libmd5.so.1 Reading symbols from /usr/platform/SUNW,Ultra-4/lib/libc_psr.so.1...done. Loaded symbols for /usr/platform/SUNW,Ultra-4/lib/libc_psr.so.1 Reading symbols from /opt/local/lib/dovecot/imap/lib10_quota_plugin.so...done. Loaded symbols for /opt/local/lib/dovecot/imap/lib10_quota_plugin.so Reading symbols from /usr/platform/SUNW,Ultra-4/lib/libmd5_psr.so.1...done. Loaded symbols for /usr/platform/SUNW,Ultra-4/lib/libmd5_psr.so.1 Reading symbols from /opt/local/lib/dovecot/imap/lib11_imap_quota_plugin.so...done. Loaded symbols for /opt/local/lib/dovecot/imap/lib11_imap_quota_plugin.so #0 0xff0b467c in strlen () from /usr/lib/libc.so.1 (gdb) bt full #0 0xff0b467c in strlen () from /usr/lib/libc.so.1 No symbol table info available. #1 0xff107588 in _doprnt () from /usr/lib/libc.so.1 No symbol table info available. #2 0xff1095f8 in vsnprintf () from /usr/lib/libc.so.1 No symbol table info available. #3 0x000e6d44 in str_vprintfa () No symbol table info available. #4 0x000d2ffc in internal_handler () No symbol table info available. #5 0x000d30b8 in i_internal_error_handler () No symbol table info available. #6 0x000d2ac0 in i_error () No symbol table info available. #7 0xfefe6cec in fs_quota_match_box () from /opt/local/lib/dovecot/imap/lib10_quota_plugin.so No symbol table info available. #8 0xfefe473c in quota_root_is_visible () from /opt/local/lib/dovecot/imap/lib10_quota_plugin.so No symbol table info available. #9 0xfefe47e4 in quota_root_iter_next () from /opt/local/lib/dovecot/imap/lib10_quota_plugin.so No symbol table info available. #10 0xfefa1430 in cmd_getquotaroot () from /opt/local/lib/dovecot/imap/lib11_imap_quota_plugin.so No symbol table info available. #11 0x00032ca8 in client_command_input () No symbol table info available. #12 0x00032f54 in client_handle_next_command () No symbol table info available. #13 0x00032fd0 in client_handle_input () No symbol table info available. #14 0x00033130 in client_input () No symbol table info available. #15 0x000dbc84 in io_loop_handler_run () No symbol table info available. #16 0x000db48c in io_loop_run () No symbol table info available. #17 0x0003e49c in main () No symbol table info available. (gdb) quit Dovecot configuration: ====================== # 1.2.6: /opt/local/etc/dovecot.conf # OS: SunOS 5.9 sun4u syslog_facility: local0 protocols: imap imaps pop3 pop3s ssl_cert_file: /etc/ssl/server.crt ssl_key_file: /etc/ssl/server.key disable_plaintext_auth: no version_ignore: yes login_dir: /opt/local/var/run/dovecot/login login_executable(default): /opt/local/libexec/dovecot/imap-login login_executable(imap): /opt/local/libexec/dovecot/imap-login login_executable(pop3): /opt/local/libexec/dovecot/pop3-login login_process_per_connection: no login_processes_count: 12 max_mail_processes: 4096 mail_location: mbox:~/Mail:INBOX=/var/mail/%u mail_debug: yes mmap_disable: yes mail_nfs_storage: yes mail_nfs_index: yes mail_drop_priv_before_exec: yes mail_executable(default): /opt/local/libexec/dovecot/imap mail_executable(imap): /opt/local/libexec/dovecot/imap mail_executable(pop3): /opt/local/libexec/dovecot/pop3 mail_plugins(default): quota imap_quota mail_plugins(imap): quota imap_quota mail_plugins(pop3): quota mail_plugin_dir(default): /opt/local/lib/dovecot/imap mail_plugin_dir(imap): /opt/local/lib/dovecot/imap mail_plugin_dir(pop3): /opt/local/lib/dovecot/pop3 imap_client_workarounds(default): delay-newmail netscape-eoh tb-extra-mailbox-sep imap_client_workarounds(imap): delay-newmail netscape-eoh tb-extra-mailbox-sep imap_client_workarounds(pop3): pop3_uidl_format(default): %08Xu%08Xv pop3_uidl_format(imap): %08Xu%08Xv pop3_uidl_format(pop3): %08Xv%08Xu pop3_client_workarounds(default): pop3_client_workarounds(imap): pop3_client_workarounds(pop3): outlook-no-nuls oe-ns-eoh namespace: type: private separator: / inbox: yes list: yes subscriptions: yes namespace: type: private separator: / prefix: Mail/ hidden: yes list: no subscriptions: yes lda: postmaster_address: postmaster(a)hrz.uni-giessen.de auth default: verbose: yes debug: yes debug_passwords: yes passdb: driver: pam userdb: driver: passwd plugin: quota: fs:Home-Verzeichnis:noenforcing quota2: fs:INBOX:noenforcing:mount=/var/mail -- Hochschulrechenzentrum der | Mail: Juergen.Obermann(a)hrz.uni-giessen.de Justus-Liebig-Universitaet | WWW: http://www.uni-giessen.de/~g033 Heinrich-Buff-Ring 44 | Tel: 0641-99-13054 (0641-99-13001) D-35392 Giessen, Germany | Fax: 0641-99-13009

2 4

[Dovecot] dovecot-1.2.6: Panic: pool_data_stack_realloc(): stack frame changed
by Mike Abbott 03 Nov '09

03 Nov '09

When playing with large numbers of IMAP keywords on dovecot-1.2.6 imap crashed: Panic: pool_data_stack_realloc(): stack frame changed Looks like either maildir_file_do() shouldn't T_BEGIN/T_END or the keywords array should start larger. 0 libSystem.B.dylib 0x00007fff875f4eba __kill + 10 1 libSystem.B.dylib 0x00007fff875f4eac kill + 14 2 libSystem.B.dylib 0x00007fff87683519 raise + 25 3 libSystem.B.dylib 0x00007fff876a4e12 abort + 90 4 imap 0x000000010008022f default_fatal_finish + 69 5 imap 0x000000010007f4b4 i_internal_error_handler + 0 6 imap 0x000000010007fa36 i_info + 0 7 imap 0x000000010008987e pool_data_stack_realloc + 150 8 imap 0x000000010007d77c buffer_alloc + 60 9 imap 0x000000010007d947 buffer_check_limits + 113 10 imap 0x000000010007dde8 buffer_append + 38 11 imap 0x0000000100060c50 mail_index_sync_keywords_apply + 88 12 imap 0x0000000100043f20 index_sync_changes_apply + 184 13 imap 0x0000000100026b6d maildir_sync_flags + 131 14 imap 0x000000010002a7c6 maildir_file_do_try + 334 15 imap 0x000000010002a34e maildir_file_do + 58 16 imap 0x00000001000263c4 maildir_sync_index + 1102 17 imap 0x000000010002588e maildir_sync_context + 806 18 imap 0x0000000100025aa4 maildir_storage_sync_init + 92 19 imap 0x000000010004ad9c mailbox_sync_init + 16 20 imap 0x000000010000ebc8 imap_sync_init + 98 21 imap 0x000000010000fd29 cmd_sync_delayed + 425 22 imap 0x000000010000814c client_handle_input + 442 23 imap 0x00000001000074ea client_input + 104 24 imap 0x0000000100087c20 io_loop_handler_run + 246 25 imap 0x0000000100086f16 io_loop_run + 56 26 imap 0x000000010001063b main + 1629 27 imap 0x00000001000010b8 start + 52 also 0 libSystem.B.dylib 0x00007fff875f4eba __kill + 10 1 libSystem.B.dylib 0x00007fff875f4eac kill + 14 2 libSystem.B.dylib 0x00007fff87683519 raise + 25 3 libSystem.B.dylib 0x00007fff876a4e12 abort + 90 4 imap 0x000000010008022f default_fatal_finish + 69 5 imap 0x000000010007f4b4 i_internal_error_handler + 0 6 imap 0x000000010007fa36 i_info + 0 7 imap 0x000000010008987e pool_data_stack_realloc + 150 8 imap 0x000000010007d77c buffer_alloc + 60 9 imap 0x000000010007d947 buffer_check_limits + 113 10 imap 0x000000010007dde8 buffer_append + 38 11 imap 0x0000000100060c50 mail_index_sync_keywords_apply + 88 12 imap 0x0000000100043f20 index_sync_changes_apply + 184 13 imap 0x0000000100026b6d maildir_sync_flags + 131 14 imap 0x000000010002a7c6 maildir_file_do_try + 334 15 imap 0x000000010002a34e maildir_file_do + 58 16 imap 0x00000001000263c4 maildir_sync_index + 1102 17 imap 0x000000010002588e maildir_sync_context + 806 18 imap 0x0000000100025aa4 maildir_storage_sync_init + 92 19 imap 0x000000010004ad9c mailbox_sync_init + 16 20 imap 0x000000010004ade0 mailbox_sync + 48 21 imap 0x00000001000058b8 cmd_select_full + 1748 22 imap 0x0000000100005d9b cmd_select + 11 23 imap 0x0000000100007de6 client_command_input + 36 24 imap 0x0000000100007f17 client_command_input + 341 25 imap 0x0000000100008086 client_handle_input + 244 26 imap 0x00000001000074ea client_input + 104 27 imap 0x0000000100087c20 io_loop_handler_run + 246 28 imap 0x0000000100086f16 io_loop_run + 56 29 imap 0x000000010001063b main + 1629 30 imap 0x00000001000010b8 start + 52

2 2

[Dovecot] Related 2.0.alpha2 issue
by Mike Abbott 03 Nov '09

03 Nov '09

Just saw this new twist to an issue I reported last week with dovecot-2.0.alpha2. I logged into an IMAP account using telnet to port 143. After 30 seconds dovecot closed the connection. Mon Nov 2 16:00:07 server dovecot[52355]: imap: dup2(-1, 5) failed: Bad file descriptor Mon Nov 2 16:00:07 server dovecot[52355]: imap: service(imap): dup2s failed Mon Nov 2 16:00:07 server dovecot[52355]: imap-login: Login: user=<userV>, method=PLAIN, rip=192.168.1.10, lip=192.168.1.7, pid=59521 Mon Nov 2 16:00:37 server dovecot[52353]: master: service(imap): Initial status notification not received in 30 seconds, killing the process Mon Nov 2 16:00:37 server dovecot[52353]: master: service(imap): child 59521 killed with signal 9 Mon Nov 2 16:00:37 server dovecot[52353]: master: service(imap): command startup failed, throttling

1 0