- dovecot - dovecot.org

[Dovecot] auth-login crash with cram-md5 (plain works) on unknown user
by Charlie Allom 16 Apr '07

16 Apr '07

Hello Timo and others.. 14:09 mail:~# dovecot --version 1.0.rc31 Install prefix ...................... : /usr/local File offsets ........................ : 64bit I/O loop method ..................... : poll File change notification method ..... : inotify Building with SSL support ........... : yes (OpenSSL) Building with IPv6 support .......... : yes Building with pop3 server ........... : yes Building with mail delivery agent .. : yes Building with GSSAPI support ........ : no Building with user database modules . : static prefetch passwd passwd-file checkpassword ldap (modules) Building with password lookup modules : passwd passwd-file shadow checkpassword ldap (modules) Building with SQL drivers ............: when logging in as an *unknown* user - with CRAM-MD5 and an LDAP pass/userdb i get this crash of the auth process: ==> /var/log/dovecot.log <== dovecot: 2007-04-12 13:50:14 Error: auth(default): file auth-request.c: line 474 (auth_request_lookup_credentials_callback): assertion failed: (request->state = = AUTH_REQUEST_STATE_PASSDB) dovecot: 2007-04-12 13:50:14 Error: auth(default): Raw backtrace: dovecot-auth [0x80665a1] -> dovecot-auth [0x80664bc] -> dovecot-auth [0x805429e] -> dovecot-auth [0x805d7fd] -> dovecot-auth [0x805dbc4] -> dovecot-auth [0x8057f3b] -> dovecot-auth(io_loop_handler_run+0x110) [0x8069730] -> dovecot-auth(io_loop_run+0x1c) [0x8068c8c] -> dovecot-auth(main+0x2fe) [0x805920e] -> /lib/libc.so.6(__libc_start_main+0xdc) [0xb7db58cc] -> dovecot-auth [0x804fb51] dovecot: 2007-04-12 13:50:14 Error: child 7163 (auth) killed with signal 6 logging in via plain works nicely: ==> /var/log/dovecot.info <== dovecot: 2007-04-12 14:08:12 Info: Dovecot v1.0.rc31 starting up dovecot: 2007-04-12 14:08:27 Info: auth(default): ldap(yeled,19.8.5.23): unknown user ldap entry of unknown user: dn: uid=yeled,ou=users,dc=playlouder,dc=com uid: yeled sn: yeled cn: Charles Allom userPassword:: ddd= objectClass: inetOrgPerson objectClass: CourierMailAlias mail: yeled(a)playlouder.com maildrop: charlie(a)playlouder.com ldap entry of known user: uid: luke sn: luke cn: Luke userPassword:: ddd= objectClass: inetOrgPerson objectClass: mspUser objectClass: CourierMailAccount mail: luke(a)playlouder.com homeDirectory: /home/l/luke gidNumber: 5000 uidNumber: 5000 and my ldap config: hosts = beta.playlouder.com dn = cn=admin,dc=playlouder,dc=com dnpass = ddd auth_bind = no ldap_version = 3 base = ou=users,dc=playlouder,dc=com scope = subtree user_attrs = homeDirectory=home,uidNumber=uid,gidNumber=gid user_filter = (&(objectClass=CourierMailAccount)(uid=%n)) pass_attrs = uid=user,userPassword=password pass_filter = (&(objectClass=CourierMailAccount)(uid=%n)) default_pass_scheme = PLAIN user_global_uid = 5000 user_global_gid = 5000 as you can see I'm setting CourierMailAlias on users with no dovecot access, and CourierMailAccount on users with imap/pop logins. the passwords in openldap are plain (b64) i have included the strace output... 13:49:54.112641 gettimeofday({1176385794, 112728}, {0, 0}) = 0 13:49:54.112757 gettimeofday({1176385794, 112771}, NULL) = 0 13:49:54.112792 poll([{fd=5, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=7, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=0, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=15, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=8, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=17, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=10, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=12, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=13, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=14, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=9, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}], 12, 579) = 0 13:49:54.691245 gettimeofday({1176385794, 691271}, {0, 0}) = 0 13:49:54.691295 gettimeofday({1176385794, 691307}, NULL) = 0 13:49:54.691328 poll([{fd=5, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=7, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=0, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=15, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=8, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=17, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=10, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=12, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=13, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=14, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=9, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}], 12, 2000) = 0 13:49:56.691229 gettimeofday({1176385796, 691257}, {0, 0}) = 0 13:49:56.691280 gettimeofday({1176385796, 691293}, NULL) = 0 13:49:56.691313 poll([{fd=5, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=7, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=0, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=15, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=8, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=17, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=10, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=12, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=13, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=14, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=9, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}], 12, 970) = 0 13:49:57.661354 gettimeofday({1176385797, 661389}, {0, 0}) = 0 13:49:57.661437 gettimeofday({1176385797, 661450}, NULL) = 0 13:49:57.661470 poll([{fd=5, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=7, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=0, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=15, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=8, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=17, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=10, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=12, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=13, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=14, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=9, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}], 12, 1030) = 0 13:49:58.691228 gettimeofday({1176385798, 691255}, {0, 0}) = 0 13:49:58.691278 gettimeofday({1176385798, 691292}, NULL) = 0 13:49:58.691312 poll([{fd=5, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=7, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=0, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=15, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=8, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=17, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=10, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=12, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=13, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=14, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=9, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}], 12, 2000) = 0 13:50:00.691326 gettimeofday({1176385800, 691362}, {0, 0}) = 0 13:50:00.691396 gettimeofday({1176385800, 691409}, NULL) = 0 13:50:00.691430 poll([{fd=5, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=7, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=0, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=15, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=8, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=17, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=10, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=12, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=13, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=14, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=9, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}], 12, 1970) = 0 13:50:02.661327 gettimeofday({1176385802, 661355}, {0, 0}) = 0 13:50:02.661387 gettimeofday({1176385802, 661401}, NULL) = 0 13:50:02.661421 poll([{fd=5, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=7, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=0, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=15, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=8, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=17, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=10, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=12, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=13, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=14, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=9, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}], 12, 30) = 0 13:50:02.691270 gettimeofday({1176385802, 691293}, {0, 0}) = 0 13:50:02.691316 gettimeofday({1176385802, 691329}, NULL) = 0 13:50:02.691348 poll([{fd=5, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=7, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=0, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=15, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=8, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=17, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=10, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=12, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=13, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=14, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=9, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}], 12, 2000) = 0 13:50:04.691291 gettimeofday({1176385804, 691327}, {0, 0}) = 0 13:50:04.691366 gettimeofday({1176385804, 691379}, NULL) = 0 13:50:04.691399 poll([{fd=5, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=7, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=0, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=15, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=8, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=17, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=10, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=12, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=13, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=14, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=9, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}], 12, 2000) = 0 13:50:06.691246 gettimeofday({1176385806, 691275}, {0, 0}) = 0 13:50:06.691298 gettimeofday({1176385806, 691312}, NULL) = 0 13:50:06.691332 poll([{fd=5, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=7, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=0, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=15, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=8, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=17, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=10, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=12, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=13, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=14, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=9, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}], 12, 970) = 0 13:50:07.661253 gettimeofday({1176385807, 661280}, {0, 0}) = 0 13:50:07.661314 gettimeofday({1176385807, 661327}, NULL) = 0 13:50:07.661347 poll([{fd=5, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=7, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=0, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=15, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=8, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=17, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=10, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=12, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=13, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=14, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=9, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}], 12, 1030) = 0 13:50:08.692164 gettimeofday({1176385808, 692193}, {0, 0}) = 0 13:50:08.692217 gettimeofday({1176385808, 692230}, NULL) = 0 13:50:08.692250 poll([{fd=5, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=7, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=0, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=15, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=8, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=17, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=10, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=12, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=13, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=14, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=9, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}], 12, 2000) = 0 13:50:10.691295 gettimeofday({1176385810, 691330}, {0, 0}) = 0 13:50:10.691352 gettimeofday({1176385810, 691365}, NULL) = 0 13:50:10.691387 poll([{fd=5, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=7, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=0, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=15, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=8, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=17, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=10, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=12, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=13, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=14, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=9, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}], 12, 1) = 0 13:50:10.702349 gettimeofday({1176385810, 702382}, {0, 0}) = 0 13:50:10.702406 gettimeofday({1176385810, 702419}, NULL) = 0 13:50:10.702439 poll([{fd=5, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=7, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=0, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=15, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=8, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=17, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=10, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=12, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=13, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=14, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=9, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}], 12, 1959) = 0 13:50:12.661239 gettimeofday({1176385812, 661268}, {0, 0}) = 0 13:50:12.661300 gettimeofday({1176385812, 661314}, NULL) = 0 13:50:12.661334 poll([{fd=5, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=7, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=0, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=15, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=8, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=17, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=10, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=12, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=13, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=14, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=9, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}], 12, 41) = 0 13:50:12.711302 gettimeofday({1176385812, 711336}, {0, 0}) = 0 13:50:12.711372 gettimeofday({1176385812, 711385}, NULL) = 0 13:50:12.711405 poll([{fd=5, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=7, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=0, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=15, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=8, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=17, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=10, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL, revents=POLLIN}, {fd=12, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=13, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=14, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=9, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}], 12, 2000) = 1 13:50:14.324672 gettimeofday({1176385814, 324698}, {0, 0}) = 0 13:50:14.324722 read(10, "AUTH\t1\tCRAM-MD5\tservice=IMAP\tlip"..., 4074) = 62 13:50:14.324786 read(4, "\275\215\332\242\353%\371\243\261\207\362c~8\365f", 16) = 16 13:50:14.324856 write(10, "CONT\t1\tPDkxODI1NzkzNzUyOTY2NTIuM"..., 76) = 76 13:50:14.324915 gettimeofday({1176385814, 324929}, NULL) = 0 13:50:14.324952 poll([{fd=5, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=7, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=0, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=15, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=8, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=17, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=10, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL, revents=POLLIN}, {fd=12, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=13, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=14, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=9, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}], 12, 387) = 1 13:50:14.330904 gettimeofday({1176385814, 330925}, {0, 0}) = 0 13:50:14.330956 read(10, "CONT\t1\teWVsZWQgMmYwZWVlZDI5M2RjO"..., 4012) = 60 13:50:14.331053 time(NULL) = 1176385814 13:50:14.331107 write(7, "0{\2\1\7cv\4\35ou=users,dc=playlouder,"..., 125) = 125 13:50:14.331184 gettimeofday({1176385814, 331198}, NULL) = 0 13:50:14.331217 poll([{fd=5, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=7, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL, revents=POLLIN}, {fd=0, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=15, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=8, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=17, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=10, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=12, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=13, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=14, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=9, events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}], 12, 380) = 1 13:50:14.332637 gettimeofday({1176385814, 332653}, {0, 0}) = 0 13:50:14.332674 time(NULL) = 1176385814 13:50:14.332707 select(1024, [7], [], NULL, {0, 0}) = 1 (in [7], left {0, 0}) 13:50:14.332827 read(7, "0\f\2\1\7e\7\n", 8) = 8 13:50:14.332864 read(7, "\1\0\4\0\4\0", 6) = 6 13:50:14.332906 time(NULL) = 1176385814 13:50:14.332947 write(2, "\1Ildap(yeled,19.8.5.23): unkn"..., 41) = 41 13:50:14.333033 write(2, "\1Ffile auth-request.c: line 474 "..., 140) = 140 13:50:14.333120 open("/etc/ld.so.cache", O_RDONLY) = 11 13:50:14.333166 fstat64(11, {st_mode=S_IFREG|0644, st_size=11099, ...}) = 0 13:50:14.333217 mmap2(NULL, 11099, PROT_READ, MAP_PRIVATE, 11, 0) = 0xb7f28000 13:50:14.333256 close(11) = 0 13:50:14.333289 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory) 13:50:14.333354 open("/lib/libgcc_s.so.1", O_RDONLY) = 11 13:50:14.333391 read(11, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\240\30"..., 512) = 512 13:50:14.333437 fstat64(11, {st_mode=S_IFREG|0644, st_size=40208, ...}) = 0 13:50:14.333488 mmap2(NULL, 43332, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 11, 0) = 0xb79e1000 13:50:14.333526 mmap2(0xb79eb000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 11, 0x9) = 0xb79eb000 13:50:14.333571 close(11) = 0 13:50:14.333632 munmap(0xb7f28000, 11099) = 0 13:50:14.333685 futex(0xb7ebe9d8, FUTEX_WAKE, 2147483647) = 0 13:50:14.333753 futex(0xb79eb824, FUTEX_WAKE, 2147483647) = 0 13:50:14.333883 write(2, "\1ERaw backtrace: dovecot-auth [0"..., 406) = 406 13:50:14.333948 rt_sigprocmask(SIG_UNBLOCK, [ABRT], NULL, 8) = 0 13:50:14.333990 tgkill(7163, 7163, SIGABRT) = 0 13:50:14.334021 --- SIGABRT (Aborted) @ 0 (0) --- -- hail eris http://rubberduck.com/

2 4

[Dovecot] Deliver die if global sieve script turn on
by M1 16 Apr '07

16 Apr '07

In my installation "deliver" die if global sieve script turn on. Is there anything I have missed? Thanks.. Flex 2.5.33 Dovecot 1.0.rc29 postfix 2.3.8 In maillog: Apr 3 13:29:58 minigrass postfix/pipe[24691]: CA194121BC5: to=<xxx(a)yyy.com>, relay=dovecot, delay=0.24, delays=0.14/0.01/0/0.09 , dsn=5.3.0, status=bounced (Command died with status 2: "/usr/libexec/dovecot/deliver". Command output: input in flex scanner failed ) In master.cf : dovecot unix - n n - - pipe flags=DRhu user=dovecot:dovecot argv=/usr/libexec/dovecot/deliver -d ${recipient} -n in dovecot.conf: mail_plugins = cmusieve quota global_script_path = /home/mail/sieve/global in /home/mail/sieve/global/dspam.sieve require "fileinto"; if header :is ""X-DSPAM-Result" "Spam" { fileinto "Spam"; } Regards

3 7

[Dovecot] LDA problem
by Azher Amin 15 Apr '07

15 Apr '07

Hi, I am using dovecot deliver as lda for sendmail. My problem is that when a user account is over quota, deliver program sends message in the logs, but the message is not bounced back to sender informing that their is disk quota problem at the recipient side. Further message remains in the mail queue. Details are below. Plz suggest how to figure out the problems: 1. Message should be removed from queue if quota is exceeded 2. Sender should get a proper response. Regards -Azher Apr 14 11:31:41 wm sm-mta[22712]: l3EFVKQY022711: to=<azher(a)wm.niit.edu.pk>, delay=00:00:00, xdelay=00:00:00, mailer=local, pri=130864, dsn=4.0.0, stat=Deferred: local mailer (/usr/dovecot/libexec/dovecot/deliver) exited with EX_TEMPFAIL -------- wm:/etc/mail# mailq MSP Queue status... /var/spool/mqueue-client is empty Total requests: 0 MTA Queue status... /var/spool/mqueue (1 request) -----Q-ID----- --Size-- -----Q-Time----- ------------Sender/Recipient----------- l3EFVKQY022711 99722 Sat Apr 14 20:31 <azher(a)niit.edu.pk> (Deferred: local mailer (/usr/dovecot/libexec/dovecot/deliver) <azher(a)wm.niit.edu.pk> Total requests: 1 ----------------------------- Sendmail.mc ========= LOCAL_CONFIG FEATURE(`masquerade_envelope')dnl FEATURE(`allmasquerade')dnl FEATURE(`always_add_domain')dnl LOCAL_CONFIG FEATURE(`use_cw_file')dnl FEATURE(`use_ct_file')dnl FEATURE(`nouucp', `reject')dnl FEATURE(`bestmx_is_local')dnl FEATURE(`mailertable')dnl FEATURE(`smrsh')dnl FEATURE(`local_procmail', `/usr/dovecot/libexec/dovecot/deliver',`deliver -d $u') dnl # FEATURE(local_procmail) MAILER_DEFINITIONS MAILER(local)dnl MAILER(smtp)dnl MODIFY_MAILER_FLAGS(`LOCAL', `-f') MAILER(procmail)dnl LOCAL_CONFIG -- This message has been scanned for viruses and dangerous content by MailScanner, and is believed to be clean.

2 7

[Dovecot] mbox sync assertion failed?
by ddalton 15 Apr '07

15 Apr '07

freebsd 6.0 Dovecot v1.0.rc25 dovecot: Apr 14 17:06:25 Error: IMAP(ddalton): file mbox-sync-rewrite.c: line 408 (mbox_sync_read_and_move): assertion failed: (need_space == (uoff_t)-mails[idx].space) dovecot: Apr 14 17:06:26 Error: child 60712 (imap) killed with signal 6 Core was generated by `imap'. Program terminated with signal 6, Aborted. Reading symbols from /usr/local/lib/libiconv.so.3...(no debugging symbols found)...done. Loaded symbols for /usr/local/lib/libiconv.so.3 Reading symbols from /lib/libc.so.6...(no debugging symbols found)...done. Loaded symbols for /lib/libc.so.6 Reading symbols from /usr/local/lib/dovecot/imap/drac.so...done. Loaded symbols for /usr/local/lib/dovecot/imap/drac.so Reading symbols from /usr/local/lib/nss_dns.so.1...done. Loaded symbols for /usr/local/lib/nss_dns.so.1 Reading symbols from /libexec/ld-elf.so.1...done. Loaded symbols for /libexec/ld-elf.so.1 #0 0x1821d4b3 in kill () from /lib/libc.so.6 (gdb) bt full #0 0x1821d4b3 in kill () from /lib/libc.so.6 No symbol table info available. #1 0x182124f0 in raise () from /lib/libc.so.6 No symbol table info available. #2 0x1828cc5c in abort () from /lib/libc.so.6 No symbol table info available. #3 0x080a7339 in i_set_failure_file () No symbol table info available. #4 0x080a6e9f in i_panic () No symbol table info available. #5 0x0807618b in mbox_sync_rewrite () No symbol table info available. #6 0x080715c8 in mbox_sync_apply_index_syncs () No symbol table info available. #7 0x080735c6 in mbox_sync () No symbol table info available. #8 0x08073a38 in mbox_storage_sync_init () No symbol table info available. #9 0x0805f7b2 in imap_sync_init () No symbol table info available. #10 0x0805fc5f in cmd_sync () No symbol table info available. #11 0x080566db in cmd_check () ---Type <return> to continue, or q <return> to quit--- No symbol table info available. #12 0x0805a43e in _client_reset_command () No symbol table info available. #13 0x0805a3d2 in _client_reset_command () No symbol table info available. #14 0x0805a584 in _client_input () No symbol table info available. #15 0x080ac6d4 in io_loop_handler_run () No symbol table info available. #16 0x080abd91 in io_loop_run () No symbol table info available. #17 0x08061330 in main () No symbol table info available. (gdb) quit

3 2

[Dovecot] dovecot pam const
by Frank Cusack 15 Apr '07

15 Apr '07

In passdb-pam.c, I found some bits about const with some PAM data types. Rather than check for each vendor of PAM, better to check for actual const usage ... some vendors have changed const-ness between releases. Also, actually testing constness is great for supporting new implementations that may come out. Here is the autoconf test I use in pam_otp_auth: --8<-- # Check PAM headers for brokenness otp_CFLAGS="$CFLAGS" # save CFLAGS="$CFLAGS $EXTRA_CFLAGS -Werror" # Linux-PAM has (incorrectly) overdone const AC_MSG_CHECKING(for extra const in PAM headers) AC_TRY_COMPILE([#include <security/pam_appl.h>], [ pam_handle_t *pamh = NULL; char *item; pam_get_item(pamh, PAM_SERVICE, (void **) &item); ], [ AC_MSG_RESULT(no) DEFINES="$DEFINES -DPAM_GET_CONST=" ], [ AC_MSG_RESULT(yes) DEFINES="$DEFINES -DPAM_GET_CONST=const" ]) # Solaris PAM has (incorrectly) underdone const AC_MSG_CHECKING(for missing const in PAM headers) AC_TRY_COMPILE([#include <security/pam_appl.h>], [ struct pam_conv *conv = NULL; struct pam_message *msg; struct pam_response *resp; conv->conv(1, &msg, &resp, conv->appdata_ptr); ], [ AC_MSG_RESULT(yes) DEFINES="$DEFINES -DPAM_CONV_CONST=" ], [ AC_MSG_RESULT(no) DEFINES="$DEFINES -DPAM_CONV_CONST=const" ]) CFLAGS="$otp_CFLAGS" # restore --8<-- And then I do pam_get_item(..., (PAM_GET_CONST void **) arg); pam_get_user(..., (PAM_GET_CONST void **) arg); conv(..., (PAM_CONV_CONST struct pam_message **) arg, ...); -frank

2 1

[Dovecot] Dovecot crashing
by ddalton 15 Apr '07

15 Apr '07

freebsd 6.0 Dovecot v1.0.rc25 dovecot: Apr 14 17:06:25 Error: IMAP(ddalton): file mbox-sync-rewrite.c: line 408 (mbox_sync_read_and_move): assertion failed: (need_space == (uoff_t)-mails[idx].space) dovecot: Apr 14 17:06:26 Error: child 60712 (imap) killed with signal 6 Core was generated by `imap'. Program terminated with signal 6, Aborted. Reading symbols from /usr/local/lib/libiconv.so.3...(no debugging symbols found)...done. Loaded symbols for /usr/local/lib/libiconv.so.3 Reading symbols from /lib/libc.so.6...(no debugging symbols found)...done. Loaded symbols for /lib/libc.so.6 Reading symbols from /usr/local/lib/dovecot/imap/drac.so...done. Loaded symbols for /usr/local/lib/dovecot/imap/drac.so Reading symbols from /usr/local/lib/nss_dns.so.1...done. Loaded symbols for /usr/local/lib/nss_dns.so.1 Reading symbols from /libexec/ld-elf.so.1...done. Loaded symbols for /libexec/ld-elf.so.1 #0 0x1821d4b3 in kill () from /lib/libc.so.6 (gdb) bt full #0 0x1821d4b3 in kill () from /lib/libc.so.6 No symbol table info available. #1 0x182124f0 in raise () from /lib/libc.so.6 No symbol table info available. #2 0x1828cc5c in abort () from /lib/libc.so.6 No symbol table info available. #3 0x080a7339 in i_set_failure_file () No symbol table info available. #4 0x080a6e9f in i_panic () No symbol table info available. #5 0x0807618b in mbox_sync_rewrite () No symbol table info available. #6 0x080715c8 in mbox_sync_apply_index_syncs () No symbol table info available. #7 0x080735c6 in mbox_sync () No symbol table info available. #8 0x08073a38 in mbox_storage_sync_init () No symbol table info available. #9 0x0805f7b2 in imap_sync_init () No symbol table info available. #10 0x0805fc5f in cmd_sync () No symbol table info available. #11 0x080566db in cmd_check () ---Type <return> to continue, or q <return> to quit--- No symbol table info available. #12 0x0805a43e in _client_reset_command () No symbol table info available. #13 0x0805a3d2 in _client_reset_command () No symbol table info available. #14 0x0805a584 in _client_input () No symbol table info available. #15 0x080ac6d4 in io_loop_handler_run () No symbol table info available. #16 0x080abd91 in io_loop_run () No symbol table info available. #17 0x08061330 in main () No symbol table info available. (gdb) quit

1 0

[Dovecot] Moving Maildir files from multiple Dovecot installs
by craig carriere 15 Apr '07

15 Apr '07

I currently have Dovecot running on a SuSE SLES 10 mail server with postfix as the MTA and a number of real linux users. I have postfix delivering to Maildir records. We are going to convert to a virtual user/domain system with PostfixAdmin. I have checked out all of our settings and they appear to work well except for a problem with authenticating against mySQL using MD5-Crypt; plaintext works well however. My question is how to transfer the maildir files from the real users Maildirs to the created virtual users. When creating a mail user PostfixAdmin will create the maildir directory. Once this is created can I simply rsync the complete old directory including the dovecot files to the new mailbox? Sorry for the newbie question. Many Thanks --------------------------------- We won't tell. Get more on shows you hate to love (and love to hate): Yahoo! TV's Guilty Pleasures list.

3 2

[Dovecot] 2 mysql dbs or 2 types of encryption
by Ken Anderson 14 Apr '07

14 Apr '07

Just upgrading to 1.0.0 and I'm trying to switch from PAM (using pam-mysql) to a direct mysql query from dovecot, and I've got both md5crypt and crypt passwords in a password db. http://wiki.dovecot.org/Authentication/PasswordSchemes seems to indicate that I have to pick 1, but that is per auth-worker, right? So, I created 2 auth workers using 2 dovecot-sql config files specifying different default encryption schemes and it seems to work. Is there any problem with this, other than a small bit of overhead for 2 queries if the first doesn't hit? Thanks, Ken Anderson Pacific.Net

2 2

[Dovecot] minor public folder problem still in v1.0.0
by Mark Nienberg 14 Apr '07

14 Apr '07

This minor problem still exists in the current version. http://www.dovecot.org/list/dovecot/2006-December/018251.html It's my own fault, I kept meaning to test one of the latest versions before the 1.0 release and never got around to it. Oh well, just mentioning it so it stays on the list of things to do. 1.0.0 update from RC15 went smoothly here. Thanks, Mark Nienberg

2 2

[Dovecot] Convert mbox to maildir
by Héctor S. Mendoza O 14 Apr '07

14 Apr '07

Hello all Ive installed a new Server with dovecot, started using it and had no problems until I noticed that I couldnt create sub-sub-folders on my imap account, Ive read for the past two days info on how to convert and I know now that instead of using mbox I need to user maildir, the issue is now to convert mbox to maildir, on the various searches I did, I found the convert wiki page (http://wiki.dovecot.org/Plugins/Convert) followed the instructions there and no luck, I always end up with an error: Eopen(/.temp.chihuahua.intranet.acorporativa.com.mx.21409.1089e702ee50f317) failed: Permission denied Error: imap dump-capability process returned 89 If I remove the line convert_mail = mbox:~/mail:INBOX=/var/mail/%u it starts correctly but I see no e-mails and no conversion takes place, since its a production server Ive reverted all changes and had to add a line (mail_location = mbox:~/mail:INBOX=/var/mail/%u) to be able to see my e-mails again. Ive tried stopping dovecot, making the changes and trying dovecot n to see the configuration, but with the same results. Any ideas on what Ive been doing wrong? TIA Héctor [root@chihuahua etc]# dovecot --version 1.0.rc15 [root@chihuahua etc]# dovecot -n # /etc/dovecot.conf 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_location: mbox:~/mail:INBOX=/var/mail/%u 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 auth default: passdb: driver: pam userdb: driver: passwd [root@chihuahua etc]#

2 2