[Dovecot] save failed to INBOX: Internal error occurred.

[Jarrod Neven]

OS: CentOS release 5.4 (Final)
Postfix: postfix-2.5.1-1.mysql.sasl2.vda.rhel5
Postfixadmin: 2.3
Dovecot: 1.2.11
Squirrelmai: 1.4.20

Configuration based on
http://wiki.dovecot.org/HowTo/DovecotLDAPostfixAdminMySQL which I have
updated a few time ;)

Using postfixadmin I have create a virtual domain and created testing users
(test1 ­ test6).  With Squirrelmail these users can login but issues arise
when composing an email to any destination every time. Squirrlemail responds
with:

ERROR: Bad or malformed request.
Server responded:  Error in IMAP command FROM: Unknown command.

Log file shows:

2010-05-25 17:42:20 ²imap-login: Info: Login:
user=<test3 at corp.letsengage.com>, method=PLAIN, rip=127.0.0.1,
lip=127.0.0.1, secured
³2010-05-25 17:42:20 ²IMAP(test3 at corp.letsengage.com): Info: Namespace :
Using permissions from /var/vmail/corp.letsengage.com/test3: mode=0700
gid=-1
³2010-05-25 17:42:20 ²IMAP(test3 at corp.letsengage.com): Info: Disconnected:
Logged out bytes=79/681
³2010-05-25 17:42:34 ²IMAP(test3 at corp.letsengage.com): Info: Effective
uid=101, gid=12, home=/var/vmail/corp.letsengage.com/test3
³2010-05-25 17:42:34 ²IMAP(test3 at corp.letsengage.com): Info: maildir:
data=/var/vmail/corp.letsengage.com/test3
³2010-05-25 17:42:34 ²IMAP(test3 at corp.letsengage.com): Info: maildir++:
root=/var/vmail/corp.letsengage.com/test3, index=, control=,
inbox=/var/vmail/corp.letsengage.com/test3
³2010-05-25 17:42:34 ²imap-login: Info: Login:
user=<test3 at corp.letsengage.com>, method=PLAIN, rip=127.0.0.1,
lip=127.0.0.1, secured
³2010-05-25 17:42:34 ²IMAP(test3 at corp.letsengage.com): Info: Namespace :
Using permissions from /var/vmail/corp.letsengage.com/test3: mode=0700
gid=-1
³2010-05-25 17:42:34 ²IMAP(test3 at corp.letsengage.com): Info: Disconnected:
Logged out bytes=79/681
³2010-05-25 17:43:32 ²deliver(test3 at corp.letsengage.com): Info: auth input:
uid=101
³2010-05-25 17:43:32 ²deliver(test3 at corp.letsengage.com): Info: auth input:
gid=12
³2010-05-25 17:43:32 ²deliver(test3 at corp.letsengage.com): Info: auth input:
home=/var/vmail/corp.letsengage.com/test3
³2010-05-25 17:43:32 ²deliver(test3 at corp.letsengage.com): Info: maildir:
data=/var/vmail/corp.letsengage.com/test3
³2010-05-25 17:43:32 ²deliver(test3 at corp.letsengage.com): Info: maildir++:
root=/var/vmail/corp.letsengage.com/test3, index=, control=,
inbox=/var/vmail/corp.letsengage.com/test3
³2010-05-25 17:43:32 ²deliver(test2 at corp.letsengage.com): Info: auth input:
uid=101
³2010-05-25 17:43:32 ²deliver(test2 at corp.letsengage.com): Info: auth input:
gid=12
³2010-05-25 17:43:32 ²deliver(test2 at corp.letsengage.com): Info: auth input:
home=/var/vmail/corp.letsengage.com/test2
³2010-05-25 17:43:32 ²deliver(test2 at corp.letsengage.com): Info: maildir:
data=/var/vmail/corp.letsengage.com/test2
³2010-05-25 17:43:32 ²deliver(test2 at corp.letsengage.com): Info: maildir++:
root=/var/vmail/corp.letsengage.com/test2, index=, control=,
inbox=/var/vmail/corp.letsengage.com/test2
³2010-05-25 17:43:32 ²deliver(test3 at corp.letsengage.com): Info: Namespace :
Using permissions from /var/vmail/corp.letsengage.com/test3: mode=0700
gid=-1
³2010-05-25 17:43:32 ²deliver(test3 at corp.letsengage.com): Error:
open(/var/vmail/corp.letsengage.com/test3/tmp/1274773412.M416972P6942./root)
failed: No such file or directory
³2010-05-25 17:43:32 ²deliver(test3 at corp.letsengage.com): Info:
msgid=<20100525062437.BA0EC5D806B at CORP.LETSENGAGE.COM>: save failed to
INBOX: Internal error occurred. Refer to server log for more information.
[2010-05-25 17:43:32]
³2010-05-25 17:43:32 ²deliver(test2 at corp.letsengage.com): Info: Namespace :
Using permissions from /var/vmail/corp.letsengage.com/test2: mode=0766
gid=-1
³2010-05-25 17:43:32 ²deliver(test2 at corp.letsengage.com): Error:
open(/var/vmail/corp.letsengage.com/test2/tmp/1274773412.M420197P6943./root)
failed: No such file or directory
³2010-05-25 17:43:32 ²deliver(test2 at corp.letsengage.com): Info:
msgid=<d6669a63009a904eaf4d268b3a1c9598.squirrel at dkpadmmail1.corp.letsengage
.com>: save failed to INBOX: Internal error occurred. Refer to server log
for more information. [2010-05-25 17:43:32]


# dovecot -n
# 1.2.11: /etc/dovecot.conf
# OS: Linux 2.6.18-164.15.1.el5 x86_64 CentOS release 5.4 (Final) ext3
base_dir: /var/run/dovecot/
log_path: /var/log/dovecot/dovecot.log
log_timestamp: ³%Y-%m-%d %H:%M:%S ²
protocols: imap pop3
listen(default): *:143
listen(imap): *:143
listen(pop3): *:110
verbose_ssl: yes
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
verbose_proctitle: yes
first_valid_uid: 101
last_valid_uid: 101
mail_location: maildir:/var/vmail/%d/%n
mail_debug: 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/lib64/dovecot/imap
mail_plugin_dir(imap): /usr/lib64/dovecot/imap
mail_plugin_dir(pop3): /usr/lib64/dovecot/pop3
mail_log_max_lines_per_sec: 900
lda:
  postmaster_address: postmaster at corp.letsengage.com
  sendmail_path: /usr/lib/sendmail
  auth_socket_path: /var/run/dovecot/auth-master
auth default:
  user: nobody
  passdb:
    driver: sql
    args: /etc/dovecot-mysql.conf
  userdb:
    driver: static
    args: uid=101 gid=12 home=/var/vmail/%d/%n
  socket:
    type: listen
    client:
      path: /var/spool/postfix/private/auth
      mode: 432
      user: postfix
      group: postfix
    master:
      path: /var/run/dovecot/auth-master
      mode: 432
      user: vmail
      group: mail
plugin:
  quota: maildir:storage=10240:messages=1000
  acl: vfile:/etc/dovecot/acls
  trash: /etc/dovecot/trash.conf


#grep -v '^ *\(#.*\)\?$'  /etc/dovecot-mysql.conf
driver = mysql
connect = host=localhost dbname=postfix user=postfix password=##REMOVED##
default_pass_scheme = PLAIN
password_query = SELECT password FROM mailbox WHERE username = '%u'