[Dovecot] master:Error @2.0 TLS login, "Too large auth data_size sent"
fyi,
hg log | head -n 7 | grep changeset changeset: 10074:1d2d55d9779e
@ LOGIN via Tbird ...
Oct 15 11:22:35 master: Error: service(imap-login): Too large auth data_size sent Oct 15 11:22:35 imap-login: Info: Disconnected: Shutting down (auth failed, 1 attempts): user=blakers@mx.pgnetwork.net, method=PLAIN, rip=192.168.1.4, lip=192.168.1.14, TLS
On Thu, 2009-10-15 at 13:45 -0700, PGNet Dev wrote:
@ LOGIN via Tbird ...
Oct 15 11:22:35 master: Error: service(imap-login): Too large auth data_size sent
Every time? I can't really think of why, but I'm rewriting this part of the code anyway. Maybe it works afterwards.
On Thu, Oct 15, 2009 at 2:02 PM, Timo Sirainen tss@iki.fi wrote:
Oct 15 11:22:35 master: Error: service(imap-login): Too large auth data_size sent
Every time?
at the moment, yes. unfortunately, i can't tell you with which recent update it appeared -- or it i had simply not noticed due to other warning/errors.
I can't really think of why, but I'm rewriting this part of the code anyway. Maybe it works afterwards.
ok. i'll keep an eye on hg ...
On Thu, Oct 15, 2009 at 2:10 PM, PGNet Dev pgnet.dev+dovecot@gmail.com wrote:
I'm rewriting this part of the code anyway. Maybe it works afterwards.
hm ... i was able to keep reproducing what i saw before. but, things change a bit if i reboot system, clean house, re-pull and re-build all ...
service dovecot-custom restart
ps ax | grep dovecot
9628 pts/2 S<+ 0:00 tail -f /var/log/dovecot/dovecot.log
/var/log/messages
9682 ? S
launch TBird ... 1st,
ps ax | grep dovecot
9682 ? S
then, almost immediately,
==> /var/log/dovecot/dovecot.log <== Oct 15 07:30:46 master: Warning: Killed with signal 15 (by pid=9672 uid=0 code=kill) Oct 15 07:30:46 master: Info: Dovecot v2.0.alpha1 starting up (core dumps disabled) Oct 15 07:30:46 imap-login: Info: Login: user=testuser@my.domain.com, method=PLAIN, rip=192.168.1.4, lip=192.168.1.14, TLS Oct 15 07:30:46 IMAP(testuser@my.domain.com): Info: Loading modules from directory: /usr/local/lib/dovecot Oct 15 07:30:46 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Oct 15 07:30:46 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Oct 15 07:30:46 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib20_autocreate_plugin.so Oct 15 07:30:46 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Oct 15 07:30:46 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib20_zlib_plugin.so Oct 15 07:30:46 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Oct 15 07:30:46 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib21_fts_squat_plugin.so Oct 15 07:30:46 IMAP(testuser@my.domain.com): Info: Effective uid=1001, gid=1000, home=/data/mail/MailStore/Dovecot/Domains/my.domain.com/Accounts/testuser Oct 15 07:30:46 IMAP(testuser@my.domain.com): Info: Quota root: name=storage=10240 backend=maildir args= Oct 15 07:30:46 IMAP(testuser@my.domain.com): Info: Namespace: type=private, prefix=, sep=/, inbox=yes, hidden=no, list=yes, subscriptions=yes Oct 15 07:30:46 IMAP(testuser@my.domain.com): Info: fs: root=/data/mail/MailStore/Dovecot/Domains/my.domain.com/Accounts/testuser, index=, control=, inbox=/data/mail/MailStore/Dovecot/Domains/my.domain.com/Accounts/testuser Oct 15 07:30:46 master: Error: service(imap): child 9653 killed with signal 11 (core dumps disabled)
==> /var/log/messages <== Oct 15 07:30:46 mx kernel: imap[9653]: segfault at 0 ip 00007f8239440133 sp 00007fff4fd3ee10 error 4 in lib10_quota_plugin.so[7f8239436000+e000]
ps ax | grep dovecot
9619 ? S
On Thu, 2009-10-15 at 14:40 -0700, PGNet Dev wrote:
On Thu, Oct 15, 2009 at 2:10 PM, PGNet Dev pgnet.dev+dovecot@gmail.com wrote:
I'm rewriting this part of the code anyway. Maybe it works afterwards.
hm ... i was able to keep reproducing what i saw before. but, things change a bit if i reboot system, clean house, re-pull and re-build all ...
Oh, right, it most likely was because of http://hg.dovecot.org/dovecot-2.0/rev/aa30fc7d771a and you didn't recompile/reinstall all binaries.
inbox=/data/mail/MailStore/Dovecot/Domains/my.domain.com/Accounts/testuser Oct 15 07:30:46 master: Error: service(imap): child 9653 killed with signal 11 (core dumps disabled)
Can you get gdb backtrace? Run "ulimit -c unlimited" before starting dovecot, then it should write core file to user's home directory. http://dovecot.org/bugreport.html
On Thu, Oct 15, 2009 at 2:46 PM, Timo Sirainen tss@iki.fi wrote:
you didn't recompile/reinstall all binaries.
hrm. though i did ... that's what the "reboot system, clean house, re-pull and re-build all ..." was about.
trying again ...
Can you get gdb backtrace? Run "ulimit -c unlimited" before starting dovecot, then it should write core file to user's home directory. http://dovecot.org/bugreport.html
yes. will post in a moment ...
easier said than done ...
On Thu, Oct 15, 2009 at 2:51 PM, PGNet Dev pgnet.dev+dovecot@gmail.com wrote:
yes. will post in a moment ...
service dovecot-custom stop rm -rf /usr/local/etc/mail/dovecot rm -rf /usr/local/include/dovecot rm -rf /usr/local/lib/dovecot rm -rf /usr/local/libexec/dovecot rm -rf /usr/local/share/doc/dovecot rm -f /usr/local/sbin/dovecot*
cd /usr/local/src/dovecot-2.0 rm config.cache hg pull hg update hg log | head -n 7 changeset: 10074:1d2d55d9779e branch: HEAD tag: tip user: Timo Sirainen tss@iki.fi date: Thu Oct 15 16:18:58 2009 -0400 summary: example-config: Updated listen setting's comment.
make distclean ./autogen.sh ./configure ... make make install
Run "ulimit -c unlimited" before starting dovecot, then it should write core file to user's home directory. http://dovecot.org/bugreport.html
* Dovecot must have been started without core dump size limitbeing too low. Run ulimit -c unlimited before starting Dovecot.
hm, already @ unlimited,
bash -c ulimit
unlimitedah, tcsh-ism,
limit coredumpsize
coredumpsize 0 kbytes
limit coredumpsize unlimited
coredumpsize unlimitedin master.conf ... service imap { protocol = imap executable = gdbhelper /usr/local/libexec/dovecot/imap ...
* Set mail_drop_priv_before_exec = yes in config file.ok, so where's this go now? if in mail.conf,
dovecot -n
# 2.0.alpha1: /data/mail/Conf/dovecot/dovecot.conf
doveconf: Fatal: Error in configuration file/data/mail/Conf/dovecot/conf.d/mail.conf line 21: Unknown setting: mail_drop_priv_before_exec
* Linux: Make sure /proc/sys/kernel/core_pattern contains a propervalue. By giving an absolute path to it (e.g. /var/core/%p) the users don't need a home directory.
cat /proc/sys/kernel/core_pattern
corebut @ startup,
tail -f /var/log/{dove*/dove*,messages}
==> /var/log/dovecot/dovecot.log <==
Oct 15 09:07:33 master: Info: Dovecot v2.0.alpha1 starting up (coredumps disabled)
how do i enable coredumps?
On Red Hat based distros, do:
echo 'DAEMON_COREFILE_LIMIT="unlimited"' >> /etc/sysconfig/dovecot && service dovecot restart
Might be worth putting in the wiki if it's not there already?
-Brad
-----Original Message----- ==> /var/log/dovecot/dovecot.log <== Oct 15 09:07:33 master: Info: Dovecot v2.0.alpha1 starting up (core dumps disabled)
how do i enable coredumps?
On Thu, Oct 15, 2009 at 4:15 PM, Brandon Davidson brandond@uoregon.edu wrote:
how do i enable coredumps?
on opensuse,
SOFTCORELIMIT="0" -> SOFTCORELIMIT="1"
in /etc/sysconfig/ulimit is good for persist across restarts
AND,
limit coredumpsize unlimited
works for in-session -- as long as i remember to do it in the RIGHT console window. argh.
limit coredumpsize unlimited cd /data/mail/Domains/my.domain.com/Accounts/testuser service dovecot-custom restart
Oct 15 09:23:37 master: Info: Dovecot v2.0.alpha1 starting up
login with TBird,
Oct 15 09:25:12 master: Error: service(imap): child 6626 killed with signal 11 (core dumped) Oct 15 09:25:12 mx kernel: imap[6626]: segfault at 0 ip 00007f9d752a5133 sp 00007fffe2288c50 error 4 in lib10_quota_plugin.so[7f9d7529b000+e000]
gdb /usr/local/libexec/dovecot/imap ./core
(gdb) bt full
#0 0x00007f9d752a5133 in maildir_quota_namespace_added
(quota=0x62fe30, ns=0x630270) at quota-maildir.c:786
No locals.
#1 0x00007f9d752a1283 in quota_add_user_namespace (quota=0x62fe30,
ns=0x630270) at quota.c:575
backends = <value optimized out>
path = 0x61fa30 "/data/mail/Domains/my.domain.com/Accounts/testuser"
path2 = <value optimized out>
i = 0
j = 8
#2 0x00007f9d752a5900 in quota_mailbox_list_created (list=0x630348)
at quota-storage.c:569
qlist = <value optimized out>
quota = (struct quota *) 0x7f9d752a785f
root = (struct quota_root *) 0x630270
#3 0x00007f9d760c2d75 in mailbox_list_create (driver=<value optimized
out>, ns=0x630270, set=0x7fffe2288df0, flags=<value optimized out>,
error_r=0x7fffe2288ed8) at mailbox-list.c:180
list = <value optimized out>
path = <value optimized out>
idx = 2
__PRETTY_FUNCTION__ = "mailbox_list_create"
#4 0x00007f9d760bfa46 in mail_storage_create (ns=0x630270,
driver=0x61f820 "maildir", flags=<value optimized out>,
error_r=0x7fffe2288ed8) at mail-storage.c:271
storage_class = (struct mail_storage *) 0x7f9d76349be0
storage = <value optimized out>
list_set = {layout = 0x61f876 "fs", root_dir = 0x61f828
"/data/mail/Domains/my.domain.com/Accounts/testuser", index_dir = 0x0,
control_dir = 0x0, alt_dir = 0x0,
inbox_path = 0x61f828
"/data/mail/Domains/my.domain.com/Accounts/testuser",
subscription_fname = 0x7f9d76134e06 "subscriptions", dir_guid_fname =
0x7f9d76131e6c "dovecot-dir-guid",
maildir_name = 0x0, mailbox_dir_name = 0x0}
data = 0x63c5a8
"maildir:/data/mail/Domains/my.domain.com/Accounts/testuser:LAYOUT=fs"
p = <value optimized out>
__PRETTY_FUNCTION__ = "mail_storage_create"
#5 0x00007f9d760badb4 in mail_namespaces_init (user=0x63b2f8,
error_r=0x7fffe2289000) at mail-namespace.c:130
mail_set = (const struct mail_storage_settings *) 0x63bbe0
namespaces = (struct mail_namespace *) 0x0
ns = <value optimized out>
ns_p = (struct mail_namespace **) 0x7fffe2288ee8
inbox_set = <value optimized out>
error = <value optimized out>
driver = <value optimized out>
location_source = <value optimized out>
i = 0
count = 3
__PRETTY_FUNCTION__ = "mail_namespaces_init"
#6 0x00007f9d760c4bae in mail_storage_service_init_post
(service=0x627370, input=0x7fffe2288fc0, home=0x7fffe228aef4
"/data/mail/Domains/my.domain.com/Accounts/testuser",
user_set=0x629428, setuid_root=false, flags=17,
mail_user_r=0x7fffe2289020, error_r=0x7fffe2289000) at
mail-storage-service.c:395
mail_set = (const struct mail_storage_settings *) 0x6294b8
mail_user = (struct mail_user *) 0x63b2f8
#7 0x00007f9d760c5a20 in mail_storage_service_init_user
(service=0x627370, _input=<value optimized out>, set_roots=<value
optimized out>, flags=17) at mail-storage-service.c:596
_data_stack_cur_id = 2
user = <value optimized out>
#8 0x0000000000417d07 in main (argc=1, argv=0x7fffe22891e8,
envp=0x7fffe22891f8) at main.c:217
---Type <return> to continue, or q <return> to quit---
set_roots = {0x61e980, 0x0}
service_flags = <value optimized out>
storage_service_flags = 17
input = {module = 0x41b2b7 "imap", service = 0x41b2b7 "imap",
username = 0x7fffe228aecd "testuser@my.domain.com", local_ip = {family
= 2, u = {ip6 = {in6_u = {
u6_addr8 = "¬\036\vj", '\0'
On Thu, 2009-10-15 at 16:32 -0700, PGNet Dev wrote:
#0 0x00007f9d752a5133 in maildir_quota_namespace_added (quota=0x62fe30, ns=0x630270) at quota-maildir.c:786
Well, kind of a stupid fix but it's probably good enough. A better one would be a lot more trouble. http://hg.dovecot.org/dovecot-2.0/rev/646a7173ad3d
On Thu, Oct 15, 2009 at 4:52 PM, Timo Sirainen tss@iki.fi wrote:
On Thu, 2009-10-15 at 16:32 -0700, PGNet Dev wrote:
#0 0x00007f9d752a5133 in maildir_quota_namespace_added (quota=0x62fe30, ns=0x630270) at quota-maildir.c:786
Well, kind of a stupid fix but it's probably good enough. A better one would be a lot more trouble. http://hg.dovecot.org/dovecot-2.0/rev/646a7173ad3d
will see if anything crops ups downstream as a result. thx.
fyi, so far, so good. logged in, with no problems. user's folders autocreated as specified,
cd /data/mail/Domains/my.domain.com/Accounts/testuser ls -al total 56 drwx------ 9 vmail vmail 4096 2009-10-15 10:16 ./ drwx------ 3 vmail vmail 4096 2009-10-15 06:38 ../ drwx------ 2 vmail vmail 4096 2009-10-15 06:38 cur/ -rw------- 1 vmail vmail 220 2009-10-15 10:16 dovecot.index.log -rw-r--r-- 1 vmail vmail 600 2009-10-15 10:16 dovecot.mailbox.log -rw------- 1 vmail vmail 51 2009-10-15 10:16 dovecot-uidlist -rw------- 1 vmail vmail 8 2009-10-15 10:16 dovecot-uidvalidity -rw------- 1 vmail vmail 0 2009-10-15 10:16 dovecot-uidvalidity.4ad758e5 drwx------ 5 vmail vmail 4096 2009-10-15 10:16 Drafts/ drwx------ 2 vmail vmail 4096 2009-10-15 06:38 new/ drwx------ 5 vmail vmail 4096 2009-10-15 10:16 Spam/ -rw------- 1 vmail vmail 34 2009-10-15 10:16 subscriptions drwx------ 5 vmail vmail 4096 2009-10-15 10:16 Templates/ drwx------ 2 vmail vmail 4096 2009-10-15 06:38 tmp/ drwx------ 5 vmail vmail 4096 2009-10-15 10:16 Trash/
one question re: logs (below). the
Info: Namespace : Permission lookup failed from/data/mail/Domains/my.domain.com/Accounts/testuser/Trash
messages are labeled as "Info", but say "failed". Are these a problem? Seems like the folders were created, and with correct permissions, after all ... 'fail' becuase there's a check _before_ they're created?.
Oct 15 10:16:15 imap-login: Info: Login: user=testuser@my.domain.com, method=PLAIN, rip=192.168.1.4, lip=192.168.1.14, TLS Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Loading modules from directory: /usr/local/lib/dovecot Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib20_autocreate_plugin.so Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib20_zlib_plugin.so Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib21_fts_squat_plugin.so Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Effective uid=1001, gid=1000, home=/data/mail/Domains/my.domain.com/Accounts/testuser Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Quota root: name=storage=10240 backend=maildir args= Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Namespace: type=private, prefix=, sep=/, inbox=yes, hidden=no, list=yes, subscriptions=yes Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: fs: root=/data/mail/Domains/my.domain.com/Accounts/testuser, index=, control=, inbox=/data/mail/Domains/my.domain.com/Accounts/testuser Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Namespace: type=public, prefix=Public/, sep=/, inbox=no, hidden=no, list=yes, subscriptions=yes Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: maildir++: root=/data/mail/MailStore/Dovecot/Domains/my.domain.com/Public, index=, control=, inbox= Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Namespace: type=shared, prefix=Shared/, sep=/, inbox=no, hidden=no, list=yes, subscriptions=yes Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: maildir++: root=/data/mail/MailStore/Dovecot/Domains/my.domain.com/Shared, index=, control=, inbox= Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Namespace : Permission lookup failed from /data/mail/Domains/my.domain.com/Accounts/testuser/Trash Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Namespace : Using permissions from /data/mail/Domains/my.domain.com/Accounts/testuser: mode=0700 gid=-1 Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Namespace : Permission lookup failed from /data/mail/Domains/my.domain.com/Accounts/testuser/Trash Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Namespace : Using permissions from /data/mail/Domains/my.domain.com/Accounts/testuser: mode=0700 gid=-1 Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Namespace : Permission lookup failed from /data/mail/Domains/my.domain.com/Accounts/testuser/Spam Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Namespace : Using permissions from /data/mail/Domains/my.domain.com/Accounts/testuser: mode=0700 gid=-1 Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Namespace : Permission lookup failed from /data/mail/Domains/my.domain.com/Accounts/testuser/Spam Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Namespace : Using permissions from /data/mail/Domains/my.domain.com/Accounts/testuser: mode=0700 gid=-1 Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Namespace : Permission lookup failed from /data/mail/Domains/my.domain.com/Accounts/testuser/Drafts Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Namespace : Using permissions from /data/mail/Domains/my.domain.com/Accounts/testuser: mode=0700 gid=-1 Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Namespace : Permission lookup failed from /data/mail/Domains/my.domain.com/Accounts/testuser/Drafts Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Namespace : Using permissions from /data/mail/Domains/my.domain.com/Accounts/testuser: mode=0700 gid=-1 Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Namespace : Permission lookup failed from /data/mail/Domains/my.domain.com/Accounts/testuser/Templates Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Namespace : Using permissions from /data/mail/Domains/my.domain.com/Accounts/testuser: mode=0700 gid=-1 Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Namespace : Permission lookup failed from /data/mail/Domains/my.domain.com/Accounts/testuser/Templates Oct 15 10:16:15 IMAP(testuser@my.domain.com): Info: Namespace : Using permissions from /data/mail/Domains/my.domain.com/Accounts/testuser: mode=0700 gid=-1 Oct 15 10:16:27 imap-login: Info: Login: user=testuser@my.domain.com, method=PLAIN, rip=192.168.1.4, lip=192.168.1.14, TLS Oct 15 10:16:27 IMAP(testuser@my.domain.com): Info: Loading modules from directory: /usr/local/lib/dovecot Oct 15 10:16:27 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Oct 15 10:16:27 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Oct 15 10:16:27 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib20_autocreate_plugin.so Oct 15 10:16:27 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Oct 15 10:16:27 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib20_zlib_plugin.so Oct 15 10:16:27 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Oct 15 10:16:27 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib21_fts_squat_plugin.so Oct 15 10:16:27 IMAP(testuser@my.domain.com): Info: Effective uid=1001, gid=1000, home=/data/mail/Domains/my.domain.com/Accounts/testuser Oct 15 10:16:27 IMAP(testuser@my.domain.com): Info: Quota root: name=storage=10240 backend=maildir args= Oct 15 10:16:27 IMAP(testuser@my.domain.com): Info: Namespace: type=private, prefix=, sep=/, inbox=yes, hidden=no, list=yes, subscriptions=yes Oct 15 10:16:27 IMAP(testuser@my.domain.com): Info: fs: root=/data/mail/Domains/my.domain.com/Accounts/testuser, index=, control=, inbox=/data/mail/Domains/my.domain.com/Accounts/testuser Oct 15 10:16:27 IMAP(testuser@my.domain.com): Info: Namespace: type=public, prefix=Public/, sep=/, inbox=no, hidden=no, list=yes, subscriptions=yes Oct 15 10:16:27 IMAP(testuser@my.domain.com): Info: maildir++: root=/data/mail/MailStore/Dovecot/Domains/my.domain.com/Public, index=, control=, inbox= Oct 15 10:16:27 IMAP(testuser@my.domain.com): Info: Namespace: type=shared, prefix=Shared/, sep=/, inbox=no, hidden=no, list=yes, subscriptions=yes Oct 15 10:16:27 IMAP(testuser@my.domain.com): Info: maildir++: root=/data/mail/MailStore/Dovecot/Domains/my.domain.com/Shared, index=, control=, inbox= Oct 15 10:16:27 IMAP(testuser@my.domain.com): Info: Namespace : Using permissions from /data/mail/Domains/my.domain.com/Accounts/testuser: mode=0700 gid=-1 Oct 15 10:16:28 imap-login: Info: Login: user=testuser@my.domain.com, method=PLAIN, rip=192.168.1.4, lip=192.168.1.14, TLS Oct 15 10:16:28 IMAP(testuser@my.domain.com): Info: Loading modules from directory: /usr/local/lib/dovecot Oct 15 10:16:28 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Oct 15 10:16:28 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Oct 15 10:16:28 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib20_autocreate_plugin.so Oct 15 10:16:28 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Oct 15 10:16:28 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib20_zlib_plugin.so Oct 15 10:16:28 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Oct 15 10:16:28 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib21_fts_squat_plugin.so Oct 15 10:16:28 IMAP(testuser@my.domain.com): Info: Effective uid=1001, gid=1000, home=/data/mail/Domains/my.domain.com/Accounts/testuser Oct 15 10:16:28 IMAP(testuser@my.domain.com): Info: Quota root: name=storage=10240 backend=maildir args= Oct 15 10:16:28 IMAP(testuser@my.domain.com): Info: Namespace: type=private, prefix=, sep=/, inbox=yes, hidden=no, list=yes, subscriptions=yes Oct 15 10:16:28 IMAP(testuser@my.domain.com): Info: fs: root=/data/mail/Domains/my.domain.com/Accounts/testuser, index=, control=, inbox=/data/mail/Domains/my.domain.com/Accounts/testuser Oct 15 10:16:28 IMAP(testuser@my.domain.com): Info: Namespace: type=public, prefix=Public/, sep=/, inbox=no, hidden=no, list=yes, subscriptions=yes Oct 15 10:16:28 IMAP(testuser@my.domain.com): Info: maildir++: root=/data/mail/MailStore/Dovecot/Domains/my.domain.com/Public, index=, control=, inbox= Oct 15 10:16:28 IMAP(testuser@my.domain.com): Info: Namespace: type=shared, prefix=Shared/, sep=/, inbox=no, hidden=no, list=yes, subscriptions=yes Oct 15 10:16:28 IMAP(testuser@my.domain.com): Info: maildir++: root=/data/mail/MailStore/Dovecot/Domains/my.domain.com/Shared, index=, control=, inbox= Oct 15 10:16:28 IMAP(testuser@my.domain.com): Info: Namespace : Using permissions from /data/mail/Domains/my.domain.com/Accounts/testuser: mode=0700 gid=-1 Oct 15 10:16:29 imap-login: Info: Login: user=testuser@my.domain.com, method=PLAIN, rip=192.168.1.4, lip=192.168.1.14, TLS Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Loading modules from directory: /usr/local/lib/dovecot Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib20_autocreate_plugin.so Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib20_zlib_plugin.so Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib21_fts_squat_plugin.so Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Effective uid=1001, gid=1000, home=/data/mail/Domains/my.domain.com/Accounts/testuser Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Quota root: name=storage=10240 backend=maildir args= Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Namespace: type=private, prefix=, sep=/, inbox=yes, hidden=no, list=yes, subscriptions=yes Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: fs: root=/data/mail/Domains/my.domain.com/Accounts/testuser, index=, control=, inbox=/data/mail/Domains/my.domain.com/Accounts/testuser Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Namespace: type=public, prefix=Public/, sep=/, inbox=no, hidden=no, list=yes, subscriptions=yes Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: maildir++: root=/data/mail/MailStore/Dovecot/Domains/my.domain.com/Public, index=, control=, inbox= Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Namespace: type=shared, prefix=Shared/, sep=/, inbox=no, hidden=no, list=yes, subscriptions=yes Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: maildir++: root=/data/mail/MailStore/Dovecot/Domains/my.domain.com/Shared, index=, control=, inbox= Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Namespace : Using permissions from /data/mail/Domains/my.domain.com/Accounts/testuser: mode=0700 gid=-1 Oct 15 10:16:29 imap-login: Info: Login: user=testuser@my.domain.com, method=PLAIN, rip=192.168.1.4, lip=192.168.1.14, TLS Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Loading modules from directory: /usr/local/lib/dovecot Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib20_autocreate_plugin.so Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib20_zlib_plugin.so Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Module loaded: /usr/local/lib/dovecot/lib21_fts_squat_plugin.so Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Effective uid=1001, gid=1000, home=/data/mail/Domains/my.domain.com/Accounts/testuser Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Quota root: name=storage=10240 backend=maildir args= Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Namespace: type=private, prefix=, sep=/, inbox=yes, hidden=no, list=yes, subscriptions=yes Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: fs: root=/data/mail/Domains/my.domain.com/Accounts/testuser, index=, control=, inbox=/data/mail/Domains/my.domain.com/Accounts/testuser Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Namespace: type=public, prefix=Public/, sep=/, inbox=no, hidden=no, list=yes, subscriptions=yes Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: maildir++: root=/data/mail/MailStore/Dovecot/Domains/my.domain.com/Public, index=, control=, inbox= Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Namespace: type=shared, prefix=Shared/, sep=/, inbox=no, hidden=no, list=yes, subscriptions=yes Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: maildir++: root=/data/mail/MailStore/Dovecot/Domains/my.domain.com/Shared, index=, control=, inbox= Oct 15 10:16:29 IMAP(testuser@my.domain.com): Info: Namespace : Using permissions from /data/mail/Domains/my.domain.com/Accounts/testuser: mode=0700 gid=-1
On 10/16/2009 02:23 AM PGNet Dev wrote:
fyi, so far, so good. logged in, with no problems. user's folders autocreated as specified, … one question re: logs (below). the
Info: Namespace : Permission lookup failed from /data/mail/Domains/my.domain.com/Accounts/testuser/Trash
messages are labeled as "Info", but say "failed". Are these a problem? Seems like the folders were created, and with correct permissions, after all ... 'fail' becuase there's a check _before_ they're created?.
src/lib-storage/mailbox-list.c:369 } else if (list->mail_set->mail_debug) { i_info("Namespace %s: Permission lookup failed from %s", list->ns->prefix, path);
This messages is logged, because you've enabled mail_debug. In a few commits, this messages will be: a) Prefixed with 'Debug' b) optional send to debug_log_path = /path/to/debug.log
Regards, Pascal
The trapper recommends today: beeffeed.0928902@localdomain.org
On Thu, Oct 15, 2009 at 5:34 PM, Pascal Volk user+dovecot@localhost.localdomain.org wrote:
This messages is logged, because you've enabled mail_debug. In a few commits, this messages will be: a) Prefixed with 'Debug' b) optional send to debug_log_path = /path/to/debug.log
thanks, that'll be useful.
On Thu, Oct 15, 2009 at 4:21 PM, PGNet Dev pgnet.dev+dovecot@gmail.com wrote:
SOFTCORELIMIT="0" -> SOFTCORELIMIT="1"
argh!
--- SOFTCORELIMIT="0" -> SOFTCORELIMIT="1" +++ --- SOFTCORELIMIT="0" -> SOFTCORELIMIT="unlimted"
On Thu, 2009-10-15 at 16:09 -0700, PGNet Dev wrote:
Run "ulimit -c unlimited" before starting dovecot, then it should write core file to user's home directory. http://dovecot.org/bugreport.html
* Dovecot must have been started without core dump size limitbeing too low. Run ulimit -c unlimited before starting Dovecot.
hm, already @ unlimited,
bash -c ulimit unlimited
ah, tcsh-ism,
limit coredumpsize coredumpsize 0 kbytes limit coredumpsize unlimited coredumpsize unlimited
If you start Dovecot via "service", it probably overrides the ulimit in some script. Make sure you see:
cat /proc/pidof dovecot/limits|grep core
Max core file size unlimited unlimited bytes
Easiest probably just to start dovecot with:
ulimit -c unlimited dovecot
in master.conf ... service imap { protocol = imap executable = gdbhelper /usr/local/libexec/dovecot/imap ...
Well, with gdbhelper you won't ever get a core. I can't actually even remember how gdbhelper is supposed to work. Just don't use it.
* Set mail_drop_priv_before_exec = yes in config file.
This is no longer necessary at all with Linux.
participants (4)
-
Brandon Davidson
-
Pascal Volk
-
PGNet Dev
-
Timo Sirainen