Jan 14 15:06:03 master: Info: Dovecot v2.2.33.2 (d6601f4ec) starting
up for imap, lmtp, sieve (core dumps disabled)
Jan 14 15:07:09 auth: Debug: Loading modules from directory:
/usr/lib/dovecot/modules/auth
Jan 14 15:07:09 auth: Debug: Module loaded:
/usr/lib/dovecot/modules/auth/lib20_auth_var_expand_crypt.so
Jan 14 15:07:09 auth: Debug: Module loaded:
/usr/lib/dovecot/modules/auth/libdriver_mysql.so
Jan 14 15:07:09 auth: Debug: Read auth token secret from
/var/run/dovecot/auth-token-secret.dat
Jan 14 15:07:09 auth: Debug: auth client connected (pid=5343)
Jan 14 15:07:10 auth: Debug: client in: AUTH 1 PLAIN
service=imap secured session=MpKNj2t/V7XV4SEm
lip=173.212.231.229 rip=213.225.33.38 lport=993 rport=46423
local_name=imap.mydomain.com
Jan 14 15:07:10 auth: Debug: client passdb out: CONT 1
Jan 14 15:07:10 auth: Debug: client in: CONT<hidden>
Jan 14 15:07:10 auth-worker(5346): Debug: Loading modules from
directory: /usr/lib/dovecot/modules/auth
Jan 14 15:07:10 auth-worker(5346): Debug: Module loaded:
/usr/lib/dovecot/modules/auth/lib20_auth_var_expand_crypt.so
Jan 14 15:07:10 auth-worker(5346): Debug: Module loaded:
/usr/lib/dovecot/modules/auth/libdriver_mysql.so
Jan 14 15:07:10 auth-worker(5346): Debug:
sql(mailus...@mydomain.com,213.225.33.38,<MpKNj2t/V7XV4SEm>): query:
SELECT username AS user, domain, password FROM accounts WHERE
username = 'mailuser1' AND domain = 'mydomain.com' and enabled = true;
Jan 14 15:07:10 auth-worker(5346): Debug:
sql(mailus...@mydomain.com,213.225.33.38,<MpKNj2t/V7XV4SEm>):
username changed mailus...@mydomain.com -> mailuser1
Jan 14 15:07:10 auth-worker(5346): Debug:
sql(mailuser1,213.225.33.38,<MpKNj2t/V7XV4SEm>): username changed
mailuser1 -> mailus...@mydomain.com
Jan 14 15:07:10 auth: Debug:
sql(mailus...@mydomain.com,213.225.33.38,<MpKNj2t/V7XV4SEm>):
username changed mailus...@mydomain.com -> mailuser1
Jan 14 15:07:10 auth: Debug:
sql(mailuser1,213.225.33.38,<MpKNj2t/V7XV4SEm>): username changed
mailuser1 -> mailus...@mydomain.com
Jan 14 15:07:10 auth: Debug: client passdb out: OK 1
user=mailus...@mydomain.com
Jan 14 15:07:10 auth: Debug: master in: REQUEST 1124466689 5343
1 f6508d0565d31959337b995fee8c8fc0 session_pid=5347
request_auth_token
Jan 14 15:07:10 auth-worker(5346): Debug:
passwd(mailus...@mydomain.com,213.225.33.38,<MpKNj2t/V7XV4SEm>): lookup
Jan 14 15:07:10 auth-worker(5346): Info:
passwd(mailus...@mydomain.com,213.225.33.38,<MpKNj2t/V7XV4SEm>):
unknown user
Jan 14 15:07:10 auth-worker(5346): Debug:
sql(mailus...@mydomain.com,213.225.33.38,<MpKNj2t/V7XV4SEm>): SELECT
concat('*:storage=', quota, 'M') AS quota_rule FROM accounts WHERE
username = 'mailuser1' AND domain = 'mydomain.com' AND sendonly = false;
Jan 14 15:07:10 auth: Debug: master userdb out: USER 1124466689
mailus...@mydomain.com quota_rule=*:storage=2048M
auth_token=c0af49e6da382961494c74d54add28b3a077f23c
Jan 14 15:07:10 imap-login: Info: Login:
user=<mailus...@mydomain.com>, method=PLAIN, rip=213.225.33.38,
lip=173.212.231.229, mpid=5347, TLS, session=<MpKNj2t/V7XV4SEm>
Jan 14 15:07:10 imap(mailus...@mydomain.com): Debug: Loading modules
from directory: /usr/lib/dovecot/modules
Jan 14 15:07:10 imap(mailus...@mydomain.com): Debug: Module loaded:
/usr/lib/dovecot/modules/lib10_quota_plugin.so
Jan 14 15:07:10 imap(mailus...@mydomain.com): Debug: Module loaded:
/usr/lib/dovecot/modules/lib11_imap_quota_plugin.so
Jan 14 15:07:10 imap(mailus...@mydomain.com): Debug: Module loaded:
/usr/lib/dovecot/modules/lib15_notify_plugin.so
Jan 14 15:07:10 imap(mailus...@mydomain.com): Debug: Module loaded:
/usr/lib/dovecot/modules/lib20_replication_plugin.so
Jan 14 15:07:10 imap(mailus...@mydomain.com): Debug: Module loaded:
/usr/lib/dovecot/modules/lib95_imap_sieve_plugin.so
Jan 14 15:07:10 imap(mailus...@mydomain.com): Debug: Added userdb
setting: plugin/quota_rule=*:storage=2048M
Jan 14 15:07:10 imap(mailus...@mydomain.com): Debug: Effective
uid=1001, gid=1001, home=
Jan 14 15:07:10 imap(mailus...@mydomain.com): Debug: quota: No quota
setting - plugin disabled
Jan 14 15:07:10 imap(mailus...@mydomain.com): Debug: replication: No
mail_replica setting - replication disabled
Jan 14 15:07:10 imap(mailus...@mydomain.com): Debug: Namespace inbox:
type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes,
subscriptions=yes
location=maildir:/home/vmail/mailboxes/mydomain.com/mailuser1Jan 14
15:07:10 imap(mailus...@mydomain.com): Debug: maildir++:
root=/home/vmail/mailboxes/mydomain.com/mailuser1, index=, indexpvt=,
control=, inbox=/home/vmail/mailboxes/mydomain.com/mailuser1, alt=
Jan 14 15:07:10 imap(mailus...@mydomain.com): Debug: Sent: Mailbox
opened because: append
Jan 14 15:07:10 imap(mailus...@mydomain.com): Debug: imapsieve:
mailbox Sent: APPEND event
Jan 14 15:07:10 imap(mailus...@mydomain.com): Error: User
mailus...@mydomain.com doesn't have home dir set, disabling duplicate
database
Jan 14 15:07:10 imap(mailus...@mydomain.com): Debug: sieve:
Pigeonhole version 0.4.21 (92477967) initializing
Jan 14 15:07:10 imap(mailus...@mydomain.com): Debug: sieve: include:
sieve_global is not set; it is currently not possible to include
`:global' scripts.
Jan 14 15:07:10 imap(mailus...@mydomain.com): Debug: sieve: Sieve
imapsieve plugin for Pigeonhole version 0.4.21 (92477967) loaded
Jan 14 15:07:10 imap(mailus...@mydomain.com): Debug: sieve: Sieve
Extprograms plugin for Pigeonhole version 0.4.21 (92477967) loaded
Jan 14 15:07:10 imap(mailus...@mydomain.com): Debug: imapsieve:
Static mailbox rule [1]: mailbox=`Spam' from=`*' causes=(COPY) =>
before=`file:/home/vmail/sieve/global/learn-spam.sieve' after=(none)
Jan 14 15:07:10 imap(mailus...@mydomain.com): Debug: imapsieve:
Static mailbox rule [2]: mailbox=`*' from=`Spam' causes=(COPY) =>
before=`file:/home/vmail/sieve/global/learn-ham.sieve' after=(none)
Jan 14 15:07:10 imap(mailus...@mydomain.com): Info: Connection closed
(append finished 0.117 secs ago) in=624 out=491
Jan 14 15:07:12 lmtp(5356): Debug: none: root=, index=, indexpvt=,
control=, inbox=, alt=
Jan 14 15:07:12 lmtp(5356): Info: Connect from local
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug: Loading modules
from directory: /usr/lib/dovecot/modules
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug: Module loaded:
/usr/lib/dovecot/modules/lib90_sieve_plugin.so
Jan 14 15:07:12 auth: Debug: master in: USER 1
mailus...@mydomain.com service=lmtp
Jan 14 15:07:12 auth-worker(5346): Debug:
passwd(mailus...@mydomain.com): lookup
Jan 14 15:07:12 auth-worker(5346): Info:
passwd(mailus...@mydomain.com): unknown user
Jan 14 15:07:12 auth-worker(5346): Debug:
sql(mailus...@mydomain.com): SELECT concat('*:storage=', quota, 'M')
AS quota_rule FROM accounts WHERE username = 'mailuser1' AND domain =
'mydomain.com' AND sendonly = false;
Jan 14 15:07:12 auth: Debug: userdb out: USER 1
mailus...@mydomain.com quota_rule=*:storage=2048M
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug: auth USER input:
mailus...@mydomain.com quota_rule=*:storage=2048M
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug: Added userdb
setting: plugin/quota_rule=*:storage=2048M
Jan 14 15:07:12 auth: Debug: master in: USER 2
mailus...@mydomain.com service=lmtp
Jan 14 15:07:12 auth-worker(5346): Debug:
passwd(mailus...@mydomain.com): lookup
Jan 14 15:07:12 auth-worker(5346): Info:
passwd(mailus...@mydomain.com): unknown user
Jan 14 15:07:12 auth-worker(5346): Debug:
sql(mailus...@mydomain.com): SELECT concat('*:storage=', quota, 'M')
AS quota_rule FROM accounts WHERE username = 'mailuser2' AND domain =
'mydomain.com' AND sendonly = false;
Jan 14 15:07:12 auth: Debug: userdb out: USER 2
mailus...@mydomain.com quota_rule=*:storage=2048M
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug: auth USER input:
mailus...@mydomain.com quota_rule=*:storage=2048M
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug: Added userdb
setting: plugin/quota_rule=*:storage=2048M
Jan 14 15:07:12 lmtp(5356, mailus...@mydomain.com): Debug: Effective
uid=1001, gid=1001, home=
Jan 14 15:07:12 lmtp(5356, mailus...@mydomain.com): Debug: Namespace
inbox: type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes,
subscriptions=yes
location=maildir:/home/vmail/mailboxes/mydomain.com/mailuser1
Jan 14 15:07:12 lmtp(5356, mailus...@mydomain.com): Debug: maildir++:
root=/home/vmail/mailboxes/mydomain.com/mailuser1, index=, indexpvt=,
control=, inbox=/home/vmail/mailboxes/mydomain.com/mailuser1, alt=
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Error: User
mailus...@mydomain.com doesn't have home dir set, disabling duplicate
database
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug: sieve:
Pigeonhole version 0.4.21 (92477967) initializing
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug: sieve: include:
sieve_global is not set; it is currently not possible to include
`:global' scripts.
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug: sieve: Sieve
imapsieve plugin for Pigeonhole version 0.4.21 (92477967) loaded
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug: sieve: Sieve
Extprograms plugin for Pigeonhole version 0.4.21 (92477967) loaded
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q: sieve: file storage: Using active Sieve
script path:
/home/vmail/sieve/mydomain.com/mailuser1/active-script.sieve
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q: sieve: file storage: Using script
storage path: /home/vmail/sieve/mydomain.com/mailuser1/scripts
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q: sieve: file storage: Relative path to
sieve storage in active link: scripts/
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q: sieve: file storage: Using Sieve script
path: /home/vmail/sieve/mydomain.com/mailuser1/active-script.sieve
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q: sieve: file storage: Storage path
`/home/vmail/sieve/mydomain.com/mailuser1/active-script.sieve' not found
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q: sieve: User has no active script in
storage `/home/vmail/sieve/mydomain.com/mailuser1/scripts'
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q: sieve: User has no personal script
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q: sieve: file storage: Using Sieve script
path: /home/vmail/sieve/global/spam-global.sieve
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q: sieve: file script: Opened script
`spam-global' from `/home/vmail/sieve/global/spam-global.sieve'
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q: sieve: Executed before user's personal
Sieve script(1): /home/vmail/sieve/global/spam-global.sieve
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug: Mailbox <lmtp
DATA>: Opened mail UID=1 because: header Message-ID (Cache file is
unusable)
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q: sieve: Opening script 1 of 1 from
`/home/vmail/sieve/global/spam-global.sieve'
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q: sieve: Loading script
/home/vmail/sieve/global/spam-global.sieve
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q: sieve: Script binary
/home/vmail/sieve/global/spam-global.svbin successfully loaded
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q: sieve: binary save: not saving binary
/home/vmail/sieve/global/spam-global.svbin, because it is already stored
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q: sieve: Executing script from
`/home/vmail/sieve/global/spam-global.svbin'
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug: Mailbox <lmtp
DATA>: Opened mail UID=1 because: header X-Spam-Flag (Cache file is
unusable)
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug: Mailbox <lmtp
DATA>: Opened mail UID=1 because: header X-Spam (Cache file is unusable)
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug: INBOX: Mailbox
opened because: lib-lda delivery
Jan 14 15:07:12 lmtp(mailus...@mydomain.com): Debug: Mailbox <lmtp
DATA>: Opened mail UID=1 because: copying
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Info:
qMUuO5CXPFzsFmailuser1YLIo4Q: sieve:
msgid=<010001684cb0001e-0eb4fc8d-d9ff-4e2a-8b28-48c89c684bf7-000...@email.amazonses.com>:
stored mail into mailbox 'INBOX'
Jan 14 15:07:13 lmtp(5356, mailus...@mydomain.com): Debug: Effective
uid=1001, gid=1001, home=
Jan 14 15:07:13 lmtp(5356, mailus...@mydomain.com): Debug: Namespace
inbox: type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes,
subscriptions=yes
location=maildir:/home/vmail/mailboxes/mydomain.com/mailuser2
Jan 14 15:07:13 lmtp(5356, mailus...@mydomain.com): Debug: maildir++:
root=/home/vmail/mailboxes/mydomain.com/mailuser2, index=, indexpvt=,
control=, inbox=/home/vmail/mailboxes/mydomain.com/mailuser2, alt=
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Error: User
mailus...@mydomain.com doesn't have home dir set, disabling duplicate
database
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug: sieve:
Pigeonhole version 0.4.21 (92477967) initializing
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug: sieve: include:
sieve_global is not set; it is currently not possible to include
`:global' scripts.
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug: sieve: Sieve
imapsieve plugin for Pigeonhole version 0.4.21 (92477967) loaded
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug: sieve: Sieve
Extprograms plugin for Pigeonhole version 0.4.21 (92477967) loaded
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q:2: sieve: file storage: Using active
Sieve script path:
/home/vmail/sieve/mydomain.com/mailuser2/active-script.sieve
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q:2: sieve: file storage: Using script
storage path: /home/vmail/sieve/mydomain.com/mailuser2/scripts
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q:2: sieve: file storage: Relative path to
sieve storage in active link: scripts/
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q:2: sieve: file storage: Using Sieve
script path:
/home/vmail/sieve/mydomain.com/mailuser2/active-script.sieve
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q:2: sieve: file storage: Storage path
`/home/vmail/sieve/mydomain.com/mailuser2/active-script.sieve' not found
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q:2: sieve: User has no active script in
storage `/home/vmail/sieve/mydomain.com/mailuser2/scripts'
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q:2: sieve: User has no personal script
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q:2: sieve: file storage: Using Sieve
script path: /home/vmail/sieve/global/spam-global.sieve
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q:2: sieve: file script: Opened script
`spam-global' from `/home/vmail/sieve/global/spam-global.sieve'
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q:2: sieve: Executed before user's
personal Sieve script(1): /home/vmail/sieve/global/spam-global.sieve
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q:2: sieve: Opening script 1 of 1 from
`/home/vmail/sieve/global/spam-global.sieve'
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q:2: sieve: Loading script
/home/vmail/sieve/global/spam-global.sieve
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q:2: sieve: Script binary
/home/vmail/sieve/global/spam-global.svbin successfully loaded
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q:2: sieve: binary save: not saving binary
/home/vmail/sieve/global/spam-global.svbin, because it is already stored
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug:
qMUuO5CXPFzsFmailuser1YLIo4Q:2: sieve: Executing script from
`/home/vmail/sieve/global/spam-global.svbin'
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug: INBOX: Mailbox
opened because: lib-lda delivery
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Debug: Mailbox <lmtp
DATA>: Opened mail UID=1 because: copying
Jan 14 15:07:13 lmtp(mailus...@mydomain.com): Info:
qMUuO5CXPFzsFmailuser1YLIo4Q:2: sieve:
msgid=<010001684cb0001e-0eb4fc8d-d9ff-4e2a-8b28-48c89c684bf7-000...@email.amazonses.com>:
stored mail into mailbox 'INBOX'
Jan 14 15:07:13 lmtp(5356): Info: Disconnect from local: Successful quit
-------------------
Thanks,
Leo
On 13/1/19 12:19 pm, Stephan Bosch wrote:
Op 13/01/2019 om 11:56 schreef subscription1:
Yes, that is what I did and it all works apart from the error
message on the server about the missing home dir
The doc seems to indicate that a home dir is needed.
If you enable mail_debug=yes and perhaps auth_debug=yes, you should
see what the imap service is using as the home directory and why.
Regards,
Stephan.
Thanks,
Leo
On 12/1/19 8:42 pm, Stephan Bosch wrote:
Op 11/01/2019 om 15:27 schreef subscription1:
I made a mistake when I moved dovecot to a new server and only
specified mail_location instead of mail_home
All I have in 10-mail.conf is
---------
mail_location = maildir:/home/vmail/mailboxes/%d/%n
------------
All emails for the few accounts I have are in these mailboxes and
I can get/see them via my mail client
I do, however, get the following error
--------------------
imap(b...@aaa.bbb): Error: User b...@aaa.bbb doesn't have home dir
set, disabling duplicate database
-------------------
After looking at https://wiki2.dovecot.org/VirtualUsers/Home I
tried the following
--------------------------------
mail_home = /home/vmail/mailboxes/%d/%n
mail_location = maildir:~/mail
------------------------------
Problem with this is that I now can't see any of my emails in the
client.
Appreciate any help on how to fix this.
Well, you changed the effective maildir directory to
/home/vmail/mailboxes/%d/%n/mail. So, if you want to use it like
that, you'll have to move the maildir for each user to that new
path template.
Regards,
Stephan.
