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(a)mathiesen.info,127.0.0.1): query: SELECT username AS user,
domain, password FROM mailbox WHERE username = 'abuse(a)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(a)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(a)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(a)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(a)mathiesen.info uid=5000 gid=5000
home=/Volumes/BrugerData/PostFix/mail/mathiesen.info/abuse(a)mathiesen.info/
mail=/Volumes/BrugerData/PostFix/mail/mathiesen.info/abuse(a)mathiesen.info/
Feb 19 11:52:20 4604ds1-ynoe dovecot[443]: imap-login: Login:
user=<abuse(a)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(a)mathiesen.info/
Feb 19 11:52:20 4604ds1-ynoe dovecot[443]: imap(abuse(a)mathiesen.info):
Debug: Effective uid=5000, gid=5000,
home=/Volumes/BrugerData/PostFix/mail/mathiesen.info/abuse(a)mathiesen.info/
Feb 19 11:52:20 4604ds1-ynoe dovecot[443]: imap(abuse(a)mathiesen.info):
Debug: mdbox autodetect:
stat(/Volumes/BrugerData/PostFix/mail/mathiesen.info/abuse(a)mathiesen.info/storage)
failed: No such file or directory
Feb 19 11:52:20 4604ds1-ynoe dovecot[443]: imap(abuse(a)mathiesen.info):
Debug: sdbox autodetect:
stat(/Volumes/BrugerData/PostFix/mail/mathiesen.info/abuse(a)mathiesen.info/mailboxes)
failed: No such file or directory
Feb 19 11:52:20 4604ds1-ynoe dovecot[443]: imap(abuse(a)mathiesen.info):
Debug: maildir++:
root=/Volumes/BrugerData/PostFix/mail/mathiesen.info/abuse(a)mathiesen.info,
index=, control=,
inbox=/Volumes/BrugerData/PostFix/mail/mathiesen.info/abuse(a)mathiesen.info,
alt=
Feb 19 11:53:01 4604ds1-ynoe dovecot[443]: imap(abuse(a)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(a)mathiesen.info,127.0.0.1): query: SELECT username AS user,
domain, password FROM mailbox WHERE username =
'macintosh(a)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(a)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(a)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(a)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(a)mathiesen.info uid=5000 gid=5000
home=/Volumes/BrugerData/PostFix/mail/mathiesen.info/macintosh(a)mathiesen.info/
mail=/Volumes/BrugerData/PostFix/mail/mathiesen.info/macintosh(a)mathiesen.info/
Feb 19 11:53:55 4604ds1-ynoe dovecot[443]: imap-login: Login:
user=<macintosh(a)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(a)mathiesen.info/
Feb 19 11:53:55 4604ds1-ynoe dovecot[443]:
imap(macintosh(a)mathiesen.info): Debug: Effective uid=5000, gid=5000,
home=/Volumes/BrugerData/PostFix/mail/mathiesen.info/macintosh(a)mathiesen.info/
Feb 19 11:53:55 4604ds1-ynoe dovecot[443]:
imap(macintosh(a)mathiesen.info): Debug: mdbox autodetect:
stat(/Volumes/BrugerData/PostFix/mail/mathiesen.info/macintosh(a)mathiesen.info/storage)
failed: No such file or directory
Feb 19 11:53:55 4604ds1-ynoe dovecot[443]:
imap(macintosh(a)mathiesen.info): Debug: sdbox autodetect:
stat(/Volumes/BrugerData/PostFix/mail/mathiesen.info/macintosh(a)mathiesen.info/mailboxes)
failed: No such file or directory
Feb 19 11:53:55 4604ds1-ynoe dovecot[443]:
imap(macintosh(a)mathiesen.info): Debug: maildir++:
root=/Volumes/BrugerData/PostFix/mail/mathiesen.info/macintosh(a)mathiesen.info,
index=, control=,
inbox=/Volumes/BrugerData/PostFix/mail/mathiesen.info/macintosh(a)mathiesen.info,
alt=
Feb 19 11:54:13 4604ds1-ynoe dovecot[443]:
imap(macintosh(a)mathiesen.info): Debug: Namespace : Using permissions
from
/Volumes/BrugerData/PostFix/mail/mathiesen.info/macintosh(a)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(a)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(a)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#