fts_autoindex - index-worker problem

[Masaki Nemoto]

dovecot-2.2.21 問い合わせ

fts_autoindex - index-worker problem

Hello List,

I installed dovecot-2.2.21 on CentOS 6.7.

I use ldap for userdb to separate an e-mail address and the user.
Index to apache solr failed in fts_authindex when I received an email
after setting.
The error message is "indexer-worker: Error: User 0029 at 09.jp lookup
failed: Unknown user"

Is there any solution?

This is current conf
--------------------------------------------
# cat /etc/dovecot/conf.d/auth-ldap.conf.ext
userdb {
  driver = ldap
  default_fields = uid=dovecot gid=dovecot home=/var/dovecot/%Ld/%Ln
  args = /etc/dovecot/dovecot-ldap.conf.ext
}
--------------------------------------------
# cat /etc/dovecot/dovecot-ldap.conf.ext
hosts = ldap01
auth_bind = no
ldap_version = 3
base = ""
user_attrs  = mailRoutingAddress=user, storeQuotaBytes=quota_rule=*:bytes=%$
user_filter = (&(objectClass=inetLocalMailRecipient)(mailLocalAddress=%u))
--------------------------------------------
# cat /etc/dovecot/conf.d/90-plugin.conf
plugin {
  fts = solr
  fts_solr = url=http://10.129.78.21:8983/solr/%Ld/
  fts_autoindex = yes
}
--------------------------------------------

This is test user infomation
--------------------------------------------
# ldapsearch -h ldap01 -x -b "" mailLocalAddress=gk12k_0029 at 08wt09.jp
mailRoutingAddress mailLocalAddress -LLL
dn: uid=gk12k_0029,dc=08wt09,dc=jp
mailRoutingAddress: 0029 at 09.jp
mailLocalAddress: gk12k_0029 at 08wt09.jp
--------------------------------------------

dovecot log of the problem
------------------
Jan 31 01:19:51 store01 dovecot: lmtp(24252): Connect from 10.129.75.77
Jan 31 01:19:51 store01 dovecot: lmtp(0029 at 09.jp): copy from <lmtp
DATA>: box=INBOX, uid=40, msgid=<foobar>, size=377
Jan 31 01:19:51 store01 dovecot: lmtp(0029 at 09.jp):
pdn7JafirFa8XgAAbv3f4A: from=hogehoge at example.jp,
to=gk12k_0029 at 08wt09.jp, vsize=388, msgid=<foobar>, saved mail to INBOX
Jan 31 01:19:51 store01 dovecot: lmtp(24252): Disconnect from
10.129.75.77: Successful quit
Jan 31 01:19:51 store01 dovecot: indexer-worker: Error: User 0029 at 09.jp
lookup failed: Unknown user

openldap log
------------------
Jan 31 01:19:51 ldap01 slapd[21617]: conn=1291 op=1 SRCH base="" scope=2
deref=0
filter="(&(objectClass=inetLocalMailRecipient)(mailLocalAddress=gk12k_0029 at 08wt09.jp))"
Jan 31 01:19:51 ldap01 slapd[21617]: conn=1291 op=1 SRCH
attr=mailRoutingAddress storeQuotaBytes
Jan 31 01:19:51 ldap01 slapd[21617]: conn=1291 op=1 SEARCH RESULT
tag=101 err=0 nentries=1 text=
Jan 31 01:19:51 ldap01 slapd[21617]: conn=1292 fd=19 ACCEPT from
IP=10.129.78.22:58335 (IP=0.0.0.0:389)
Jan 31 01:19:51 ldap01 slapd[21617]: conn=1292 op=0 BIND dn="" method=128
Jan 31 01:19:51 ldap01 slapd[21617]: conn=1292 op=0 RESULT tag=97 err=0
text=
Jan 31 01:19:51 ldap01 slapd[21617]: conn=1292 op=1 SRCH base="" scope=2
deref=0 filter="(mailRoutingAddress=0029 at 09.jp)"
Jan 31 01:19:51 ldap01 slapd[21617]: conn=1292 op=1 SRCH
attr=modifyTimestamp
Jan 31 01:19:51 ldap01 slapd[21617]: conn=1292 op=1 SEARCH RESULT
tag=101 err=0 nentries=1 text=
Jan 31 01:19:51 ldap01 slapd[21617]: conn=1292 op=2 SRCH
base="uid=gk12k_0029,dc=08wt09,dc=jp" scope=0 deref=0
filter="(objectClass=*)"
Jan 31 01:19:51 ldap01 slapd[21617]: conn=1292 op=2 SRCH
attr=storeSieveScript
Jan 31 01:19:51 ldap01 slapd[21617]: conn=1292 op=2 SEARCH RESULT
tag=101 err=0 nentries=1 text=
Jan 31 01:19:51 ldap01 slapd[21617]: conn=1292 op=3 UNBIND
Jan 31 01:19:51 ldap01 slapd[21617]: conn=1292 fd=19 closed
Jan 31 01:19:51 ldap01 slapd[21617]: conn=1291 op=2 SRCH base="" scope=2
deref=0
filter="(&(objectClass=inetLocalMailRecipient)(mailLocalAddress=0029 at 09.jp))"
Jan 31 01:19:51 ldap01 slapd[21617]: conn=1291 op=2 SRCH
attr=mailRoutingAddress storeQuotaBytes
Jan 31 01:19:51 ldap01 slapd[21617]: conn=1291 op=2 SEARCH RESULT
tag=101 err=0 nentries=0 text=
-------------------------------------------



Log when I deleted "fts_autoindex = yes"
-------------------------------------------
Jan 31 01:18:12 store01 dovecot: lmtp(24232): Connect from 10.129.75.77
Jan 31 01:18:13 store01 dovecot: lmtp(0029 at 09.jp): copy from <lmtp
DATA>: box=INBOX, uid=39, msgid=<foobar>, size=377
Jan 31 01:18:13 store01 dovecot: lmtp(0029 at 09.jp):
10FxNETirFaoXgAAbv3f4A: from=hogehoge at example.jp,
to=gk12k_0029 at 08wt09.jp, vsize=388, msgid=<foobar>, saved mail to INBOX
Jan 31 01:18:13 store01 dovecot: lmtp(24232): Disconnect from
10.129.75.77: Successful quit
-------------------------------------------
Jan 31 01:18:12 ldap01 slapd[21617]: conn=1289 op=1 SRCH base="" scope=2
deref=0
filter="(&(objectClass=inetLocalMailRecipient)(mailLocalAddress=gk12k_0029 at 08wt09.jp))"
Jan 31 01:18:12 ldap01 slapd[21617]: conn=1289 op=1 SRCH
attr=mailRoutingAddress storeQuotaBytes
Jan 31 01:18:12 ldap01 slapd[21617]: conn=1289 op=1 SEARCH RESULT
tag=101 err=0 nentries=1 text=
Jan 31 01:18:12 ldap01 slapd[21617]: conn=1290 fd=19 ACCEPT from
IP=10.129.78.22:58332 (IP=0.0.0.0:389)
Jan 31 01:18:12 ldap01 slapd[21617]: conn=1290 op=0 BIND dn="" method=128
Jan 31 01:18:12 ldap01 slapd[21617]: conn=1290 op=0 RESULT tag=97 err=0
text=
Jan 31 01:18:12 ldap01 slapd[21617]: conn=1290 op=1 SRCH base="" scope=2
deref=0 filter="(mailRoutingAddress=0029 at 09.jp)"
Jan 31 01:18:12 ldap01 slapd[21617]: conn=1290 op=1 SRCH
attr=modifyTimestamp
Jan 31 01:18:12 ldap01 slapd[21617]: conn=1290 op=1 SEARCH RESULT
tag=101 err=0 nentries=1 text=
Jan 31 01:18:12 ldap01 slapd[21617]: conn=1290 op=2 SRCH
base="uid=gk12k_0029,dc=08wt09,dc=jp" scope=0 deref=0
filter="(objectClass=*)"
Jan 31 01:18:12 ldap01 slapd[21617]: conn=1290 op=2 SRCH
attr=storeSieveScript
Jan 31 01:18:12 ldap01 slapd[21617]: conn=1290 op=2 SEARCH RESULT
tag=101 err=0 nentries=1 text=
Jan 31 01:18:12 ldap01 slapd[21617]: conn=1290 op=3 UNBIND
Jan 31 01:18:12 ldap01 slapd[21617]: conn=1290 fd=19 closed
-------------------------------------------

-- 
Masaki