[Dovecot] dovecot freezes when trying to get mail from maildir with mail

postmaster postmaster at mathiesen.info
Sun Feb 19 14:53:25 EET 2012 

I'm completely stumped by this problem and I've tried everything at 
present - including installing dovecot from scratch.

The problem is that when trying to get email from the server -AND- 
there's email in the account, the pop3 or imap process freezes and no 
email is fetched. The crazy thing is, that it has been working 
perfectly, but suddenly I'm getting these freezes both when using telnet 
from localhost, roundcubemail on the same server and Seamonkey from 
another computer.

Logging seems to work perfectly.
For an account without email, dovecot finds the maildir++ and acts 
correctly.
For an account -with- email, dovecot seems to find the maildir++, but 
also seems to try Namespace of which I haven't defined any.

I've tried to get rawlog to work also, but that didn't work - the telnet 
process froze on login even though I'm quite sure I did the right thing 
and ps showed an active rawlog process

Postfix is delivering the email straight into the mailboxes.

Here is the information
4604ds1-ynoe:~ root# port installed | grep dovecot
   dovecot2 @2.0.18_0+mysql5+postgresql91 (active)
   dovecot2-sieve @0.2.5_1 (active)
   postfix @2.9.0_0+dovecot_sasl+mysql5+pcre+postgresql91 (active)
4604ds1-ynoe:~ root#

4604ds1-ynoe:log root# cat dovecot.log.txt
Feb 19 11:46:52 4604ds1-ynoe dovecot[441]: master: Dovecot v2.0.18 
starting up (core dumps disabled)
Feb 19 11:51:47 4604ds1-ynoe dovecot[443]: auth: Debug: Loading modules 
from directory: /macports/lib/dovecot/auth
Feb 19 11:51:47 4604ds1-ynoe dovecot[443]: auth: Debug: auth client 
connected (pid=495)
Feb 19 11:52:20 4604ds1-ynoe dovecot[443]: auth: Debug: client in: 
AUTH	1	PLAIN	service=imap	secured	lip=127.0.0.1	rip=127.0.0.1 
lport=143	rport=49180	resp=AGFidXNlQG1hdGhpZXNlbi5pbmZvAHRlc3R0ZXN0
Feb 19 11:52:20 4604ds1-ynoe dovecot[443]: auth-worker: Debug: Loading 
modules from directory: /macports/lib/dovecot/auth
Feb 19 11:52:20 4604ds1-ynoe dovecot[443]: auth-worker: 
mysql(/macports/var/run/mysql5/mysqld.sock): Connected to database 
infomathiesenpostfixadmin
Feb 19 11:52:20 4604ds1-ynoe dovecot[443]: auth-worker: Debug: 
sql(abuse at mathiesen.info,127.0.0.1): query: SELECT username AS user, 
domain, password FROM mailbox WHERE username = 'abuse at mathiesen.info' 
AND domain = 'mathiesen.info' AND active = 1
Feb 19 11:52:20 4604ds1-ynoe dovecot[443]: auth: Debug: client out: 
OK	1	user=abuse at mathiesen.info
Feb 19 11:52:20 4604ds1-ynoe dovecot[443]: auth: Debug: master in: 
REQUEST	738852865	495	1	687658e957c0e4f473891548eecc49be
Feb 19 11:52:20 4604ds1-ynoe dovecot[443]: auth-worker: Debug: 
sql(abuse at mathiesen.info,127.0.0.1): SELECT  5000 AS uid , 5000 AS gid , 
concat ( '/Volumes/BrugerData/PostFix/mail/' , maildir ) AS home , 
concat ( '/Volumes/BrugerData/PostFix/mail/' , maildir ) AS mail FROM 
mailbox WHERE username = 'abuse at mathiesen.info' AND domain = 
'mathiesen.info' AND active = 1
Feb 19 11:52:20 4604ds1-ynoe dovecot[443]: auth: Debug: master out: 
USER	738852865	abuse at mathiesen.info	uid=5000	gid=5000 
home=/Volumes/BrugerData/PostFix/mail/mathiesen.info/abuse at mathiesen.info/ 
mail=/Volumes/BrugerData/PostFix/mail/mathiesen.info/abuse at mathiesen.info/
Feb 19 11:52:20 4604ds1-ynoe dovecot[443]: imap-login: Login: 
user=<abuse at mathiesen.info>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, 
mpid=503, secured
Feb 19 11:52:20 4604ds1-ynoe dovecot[443]: imap: Debug: Added userdb 
setting: 
mail=/Volumes/BrugerData/PostFix/mail/mathiesen.info/abuse at mathiesen.info/
Feb 19 11:52:20 4604ds1-ynoe dovecot[443]: imap(abuse at mathiesen.info): 
Debug: Effective uid=5000, gid=5000, 
home=/Volumes/BrugerData/PostFix/mail/mathiesen.info/abuse at mathiesen.info/
Feb 19 11:52:20 4604ds1-ynoe dovecot[443]: imap(abuse at mathiesen.info): 
Debug: mdbox autodetect: 
stat(/Volumes/BrugerData/PostFix/mail/mathiesen.info/abuse at mathiesen.info/storage) 
failed: No such file or directory
Feb 19 11:52:20 4604ds1-ynoe dovecot[443]: imap(abuse at mathiesen.info): 
Debug: sdbox autodetect: 
stat(/Volumes/BrugerData/PostFix/mail/mathiesen.info/abuse at mathiesen.info/mailboxes) 
failed: No such file or directory
Feb 19 11:52:20 4604ds1-ynoe dovecot[443]: imap(abuse at mathiesen.info): 
Debug: maildir++: 
root=/Volumes/BrugerData/PostFix/mail/mathiesen.info/abuse at mathiesen.info, 
index=, control=, 
inbox=/Volumes/BrugerData/PostFix/mail/mathiesen.info/abuse at mathiesen.info, 
alt=
Feb 19 11:53:01 4604ds1-ynoe dovecot[443]: imap(abuse at mathiesen.info): 
Disconnected: Logged out bytes=45/750
Feb 19 11:53:06 4604ds1-ynoe dovecot[443]: auth: Debug: auth client 
connected (pid=512)
Feb 19 11:53:55 4604ds1-ynoe dovecot[443]: auth: Debug: client in: 
AUTH	1	PLAIN	service=imap	secured	lip=127.0.0.1	rip=127.0.0.1 
lport=143	rport=49182	resp=AG1hY2ludG9zaEBtYXRoaWVzZW4uaW5mbwB0ZXN0dGVzdA==
Feb 19 11:53:55 4604ds1-ynoe dovecot[443]: auth-worker: Debug: 
sql(macintosh at mathiesen.info,127.0.0.1): query: SELECT username AS user, 
domain, password FROM mailbox WHERE username = 
'macintosh at mathiesen.info' AND domain = 'mathiesen.info' AND active = 1
Feb 19 11:53:55 4604ds1-ynoe dovecot[443]: auth: Debug: client out: 
OK	1	user=macintosh at mathiesen.info
Feb 19 11:53:55 4604ds1-ynoe dovecot[443]: auth: Debug: master in: 
REQUEST	1866334209	512	1	332714ca2a070d7adb342d4baba4a19a
Feb 19 11:53:55 4604ds1-ynoe dovecot[443]: auth-worker: Debug: 
sql(macintosh at mathiesen.info,127.0.0.1): SELECT  5000 AS uid , 5000 AS 
gid , concat ( '/Volumes/BrugerData/PostFix/mail/' , maildir ) AS home , 
concat ( '/Volumes/BrugerData/PostFix/mail/' , maildir ) AS mail FROM 
mailbox WHERE username = 'macintosh at mathiesen.info' AND domain = 
'mathiesen.info' AND active = 1
Feb 19 11:53:55 4604ds1-ynoe dovecot[443]: auth: Debug: master out: 
USER	1866334209	macintosh at mathiesen.info	uid=5000	gid=5000 
home=/Volumes/BrugerData/PostFix/mail/mathiesen.info/macintosh at mathiesen.info/ 
mail=/Volumes/BrugerData/PostFix/mail/mathiesen.info/macintosh at mathiesen.info/
Feb 19 11:53:55 4604ds1-ynoe dovecot[443]: imap-login: Login: 
user=<macintosh at mathiesen.info>, method=PLAIN, rip=127.0.0.1, 
lip=127.0.0.1, mpid=522, secured
Feb 19 11:53:55 4604ds1-ynoe dovecot[443]: imap: Debug: Added userdb 
setting: 
mail=/Volumes/BrugerData/PostFix/mail/mathiesen.info/macintosh at mathiesen.info/
Feb 19 11:53:55 4604ds1-ynoe dovecot[443]: 
imap(macintosh at mathiesen.info): Debug: Effective uid=5000, gid=5000, 
home=/Volumes/BrugerData/PostFix/mail/mathiesen.info/macintosh at mathiesen.info/
Feb 19 11:53:55 4604ds1-ynoe dovecot[443]: 
imap(macintosh at mathiesen.info): Debug: mdbox autodetect: 
stat(/Volumes/BrugerData/PostFix/mail/mathiesen.info/macintosh at mathiesen.info/storage) 
failed: No such file or directory
Feb 19 11:53:55 4604ds1-ynoe dovecot[443]: 
imap(macintosh at mathiesen.info): Debug: sdbox autodetect: 
stat(/Volumes/BrugerData/PostFix/mail/mathiesen.info/macintosh at mathiesen.info/mailboxes) 
failed: No such file or directory
Feb 19 11:53:55 4604ds1-ynoe dovecot[443]: 
imap(macintosh at mathiesen.info): Debug: maildir++: 
root=/Volumes/BrugerData/PostFix/mail/mathiesen.info/macintosh at mathiesen.info, 
index=, control=, 
inbox=/Volumes/BrugerData/PostFix/mail/mathiesen.info/macintosh at mathiesen.info, 
alt=
Feb 19 11:54:13 4604ds1-ynoe dovecot[443]: 
imap(macintosh at mathiesen.info): Debug: Namespace : Using permissions 
from 
/Volumes/BrugerData/PostFix/mail/mathiesen.info/macintosh at mathiesen.info: mode=0700 
gid=-1
Feb 19 12:12:16 4604ds1-ynoe dovecot[441]: master: Error: service(imap): 
child 522 killed with signal 3
Feb 19 12:15:48 4604ds1-ynoe dovecot[441]: master: Warning: Killed with 
signal 15 (by pid=1086 uid=0 code=unknown 0)
4604ds1-ynoe:log root#

4604ds1-ynoe:mail root# ps -axj | grep dovecot
root       441     1   441 56804e0    0 Ss     ??    0:00.01 
/macports/sbin/dovecot -c /macports/etc/dovecot/dovecot.conf
_dovecot   442   441   441 56804e0    0 S      ??    0:00.01 dovecot/anvil
root       443   441   441 56804e0    0 S      ??    0:00.01 dovecot/log
5000       522   441   441 56804e0    0 S      ??    0:00.01 dovecot/imap
root       452   362   452 5680af8    1 S+   s001    0:00.00 tail -f 
dovecot.log
root       543   380   542 4d84c30    2 R+   s002    0:00.00 grep dovecot
4604ds1-ynoe:mail root# kill -QUIT 522
4604ds1-ynoe:mail root# dovecot stop
4604ds1-ynoe:mail root# ps -axj | grep dovecot
root       452   362   452 5680af8    1 S+   s001    0:00.00 tail -f 
dovecot.log
root      1091   380  1090 4d84c30    2 R+   s002    0:00.00 grep dovecot
4604ds1-ynoe:mail root#


4604ds1-ynoe:~ root# telnet localhost 143
Trying 127.0.0.1...
Connected to localhost (127.0.0.1).
Escape character is '^]'.
* OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE 
IDLE AUTH=PLAIN] Dovecot ready.
a login "abuse at mathiesen.info" "x"
a OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE 
IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS MULTIAPPEND 
UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE 
QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS] Logged in
a list "" *
* LIST (\HasNoChildren) "." "INBOX"
a OK List completed.
a select inbox
* FLAGS (\Answered \Flagged \Deleted \Seen \Draft)
* OK [PERMANENTFLAGS (\Answered \Flagged \Deleted \Seen \Draft \*)] 
Flags permitted.
* 0 EXISTS
* 0 RECENT
* OK [UIDVALIDITY 1326334461] UIDs valid
* OK [UIDNEXT 4] Predicted next UID
* OK [HIGHESTMODSEQ 1] Highest
a OK [READ-WRITE] Select completed.
a logout
* BYE Logging out
a OK Logout completed.
Connection closed by foreign host.
4604ds1-ynoe:~ root# telnet localhost 143
Trying 127.0.0.1...
Connected to localhost (127.0.0.1).
Escape character is '^]'.
* OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE 
IDLE AUTH=PLAIN] Dovecot ready.
a login "macintosh at mathiesen.info" "x"
a OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE 
IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS MULTIAPPEND 
UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE 
QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS] Logged in
a list "" *
* LIST (\HasNoChildren) "." "INBOX"
a OK List completed.
a select inbox
Connection closed by foreign host.
4604ds1-ynoe:~ root#


4604ds1-ynoe:~ root# dovecot -n
# 2.0.18: /macports/etc/dovecot/dovecot.conf
# OS: Darwin 10.8.0 i386  hfs
auth_debug = yes
auth_debug_passwords = yes
auth_verbose = yes
auth_verbose_passwords = plain
base_dir = /macports/var/run/dovecot/
default_internal_user = _dovecot
default_login_user = _dovenull
disable_plaintext_auth = no
first_valid_gid = 5000
first_valid_uid = 5000
mail_debug = yes
mail_gid = 5000
mail_location = maildir:/Volumes/BrugerData/PostFix/mail/%d/%u
mail_uid = 5000
managesieve_notify_capability = mailto
managesieve_sieve_capability = fileinto reject envelope 
encoded-character vacation subaddress comparator-i;ascii-numeric 
relational regex imap4flags copy include variables body enotify 
environment mailbox date ihave
mmap_disable = yes
passdb {
   args = /macports/etc/dovecot/dovecot-sql.conf.ext
   driver = sql
}
plugin {
   mail_log_events = delete undelete expunge copy mailbox_delete 
mailbox_rename
   mail_log_fields = uid box msgid size
}
protocols = imap pop3
service postlogin {
   executable = script-login -d rawlog
}
ssl = no
syslog_facility = local2
userdb {
   args = /macports/etc/dovecot/dovecot-sql.conf.ext
   driver = sql
}
protocol pop3 {
   pop3_client_workarounds = oe-ns-eoh outlook-no-nuls
}
4604ds1-ynoe:~ root#

More information about the dovecot mailing list