Bug: Dovecot index loosing sync with FTS despite "fts_autoindex = yes"

kfx

17 Apr 2018 17 Apr '18

3:18 p.m.

dovecot 2.2.34 solr 7.2

I only see new messages after typing on the server "doveadm fts rescan -u username" though I've followed the wiki and added "fts_autoindex = yes" in 90-plugin.conf . Subsequent search for the same pattern always gives the same result, ignoring new emails with that particular pattern. Solr is working correctly and the correct search results comes immediately (whatever the searched word) after a "doveadm fts rescan"

# doveconf | grep fts mail_plugins = " quota stats fts fts_solr" fts = solr fts_autoindex = yes fts_solr = url=http://localhost:8983/solr/dovecot/ [POP] mail_plugins = " quota stats fts fts_solr sieve virtual" [IMAP] mail_plugins = " quota stats fts fts_solr imap_quota virtual imap_stats"

Show replies by date

kadafax＠gmail.com

17 Apr 17 Apr

11:01 p.m.

Le 17/04/2018 à 14:18, kfx a écrit :

...

dovecot 2.2.34 solr 7.2

I only see new messages after typing on the server "doveadm fts rescan -u username" though I've followed the wiki and added "fts_autoindex = yes" in 90-plugin.conf . Subsequent search for the same pattern always gives the same result, ignoring new emails with that particular pattern. Solr is working correctly and the correct search results comes immediately (whatever the searched word) after a "doveadm fts rescan"

# doveconf | grep fts mail_plugins = " quota stats fts fts_solr" fts = solr fts_autoindex = yes fts_solr = url=http://localhost:8983/solr/dovecot/ [POP] mail_plugins = " quota stats fts fts_solr sieve virtual" [IMAP] mail_plugins = " quota stats fts fts_solr imap_quota virtual imap_stats"

Here are some debug logs from a search (responding with incorrect - or old - results, as new emails have arrived, containing the same "MySearchPattern" pattern). This leads me to a problem on the dovecot side. Only a "doveadm fts rescan -u username" can solve this situation, so should I add a cronjob for it ? It defeats the purpose of solr as the first search after is really slow (but correct).

Here are the logs, please tell me if I should provide more info, and thanks for any help:

Apr 17 21:44:56 imap(MyUsername): Debug: http-client: host localhost: Host created Apr 17 21:44:56 imap(MyUsername): Debug: http-client: host localhost: DNS lookup successful; got 2 IPs Apr 17 21:44:56 imap(MyUsername): Debug: http-client: peer [::1]:8983: Peer created Apr 17 21:44:56 imap(MyUsername): Debug: http-client: queue http://localhost:8983: Setting up connection to [::1]:8983 (1 requests pending) Apr 17 21:44:56 imap(MyUsername): Debug: http-client: peer [::1]:8983: Linked queue http://localhost:8983 (1 queues linked) Apr 17 21:44:56 imap(MyUsername): Debug: http-client: queue http://localhost:8983: Started new connection to [::1]:8983 Apr 17 21:44:56 imap(MyUsername): Debug: http-client: request [Req1: GET http://localhost:8983/solr/dovecot/select?fl=uid,score&rows=25495&sort=uid+asc&q=%7b!lucene+q.op%3dAND%7d(hdr:MySearchPattern+OR+body:MySearchPattern)&fq=%2Bbox:e09cce0283e8695ab760000002deed92+%2Buser:MyUsername]: Submitted Apr 17 21:44:56 imap(MyUsername): Debug: http-client: Waiting for 1 requests to finish Apr 17 21:44:56 imap(MyUsername): Debug: http-client: peer [::1]:8983: Creating 1 new connections to handle requests (already 0 usable, connecting to 0, closing 0) Apr 17 21:44:56 imap(MyUsername): Debug: http-client: peer [::1]:8983: Making new connection 1 of 1 Apr 17 21:44:56 imap(MyUsername): Debug: http-client: conn [::1]:8983 [0]: HTTP connection created (1 parallel connections exist) Apr 17 21:44:56 imap(MyUsername): Debug: http-client: conn [::1]:8983 [0]: Connected Apr 17 21:44:56 imap(MyUsername): Debug: http-client: conn [::1]:8983 [0]: Ready for requests Apr 17 21:44:56 imap(MyUsername): Debug: http-client: peer [::1]:8983: Successfully connected (connections=1) Apr 17 21:44:56 imap(MyUsername): Debug: http-client: peer [::1]:8983: Using 1 idle connections to handle 1 requests (1 total connections ready) Apr 17 21:44:56 imap(MyUsername): Debug: http-client: queue http://localhost:8983: Connection to peer [::1]:8983 claimed request [Req1: GET http://localhost:8983/solr/dovecot/select?fl=uid,score&rows=25495&sort=uid+asc&q=%7b!lucene+q.op%3dAND%7d(hdr:MySearchPattern+OR+body:MySearchPattern)&fq=%2Bbox:e09cce0283e8695ab760000002deed92+%2Buser:MyUsername] Apr 17 21:44:56 imap(MyUsername): Debug: http-client: conn [::1]:8983 [0]: Claimed request [Req1: GET http://localhost:8983/solr/dovecot/select?fl=uid,score&rows=25495&sort=uid+asc&q=%7b!lucene+q.op%3dAND%7d(hdr:MySearchPattern+OR+body:MySearchPattern)&fq=%2Bbox:e09cce0283e8695ab760000002deed92+%2Buser:MyUsername] Apr 17 21:44:56 imap(MyUsername): Debug: http-client: request [Req1: GET http://localhost:8983/solr/dovecot/select?fl=uid,score&rows=25495&sort=uid+asc&q=%7b!lucene+q.op%3dAND%7d(hdr:MySearchPattern+OR+body:MySearchPattern)&fq=%2Bbox:e09cce0283e8695ab760000002deed92+%2Buser:MyUsername]: Sent header Apr 17 21:44:56 imap(MyUsername): Debug: http-client: peer [::1]:8983: No more requests to service for this peer (1 connections exist) Apr 17 21:44:56 imap(MyUsername): Debug: http-client: conn [::1]:8983 [0]: Got 200 response for request [Req1: GET http://localhost:8983/solr/dovecot/select?fl=uid,score&rows=25495&sort=uid+asc&q=%7b!lucene+q.op%3dAND%7d(hdr:MySearchPattern+OR+body:MySearchPattern)&fq=%2Bbox:e09cce0283e8695ab760000002deed92+%2Buser:MyUsername] (took 109 ms + 23 ms in queue) Apr 17 21:44:56 imap(MyUsername): Debug: http-client: conn [::1]:8983 [0]: Response payload stream destroyed (0 ms after initial response) Apr 17 21:44:56 imap(MyUsername): Debug: http-client: request [Req1: GET http://localhost:8983/solr/dovecot/select?fl=uid,score&rows=25495&sort=uid+asc&q=%7b!lucene+q.op%3dAND%7d(hdr:MySearchPattern+OR+body:MySearchPattern)&fq=%2Bbox:e09cce0283e8695ab760000002deed92+%2Buser:MyUsername]: Finished Apr 17 21:44:56 imap(MyUsername): Debug: http-client: queue http://localhost:8983: Dropping request [Req1: GET http://localhost:8983/solr/dovecot/select?fl=uid,score&rows=25495&sort=uid+asc&q=%7b!lucene+q.op%3dAND%7d(hdr:MySearchPattern+OR+body:MySearchPattern)&fq=%2Bbox:e09cce0283e8695ab760000002deed92+%2Buser:MyUsername] Apr 17 21:44:56 imap(MyUsername): Debug: http-client: host localhost: Host is idle (timeout = 1799867 msecs) Apr 17 21:44:56 imap(MyUsername): Debug: http-client: request [Req1: GET http://localhost:8983/solr/dovecot/select?fl=uid,score&rows=25495&sort=uid+asc&q=%7b!lucene+q.op%3dAND%7d(hdr:MySearchPattern+OR+body:MySearchPattern)&fq=%2Bbox:e09cce0283e8695ab760000002deed92+%2Buser:MyUsername]: Free (requests left=1) Apr 17 21:44:56 imap(MyUsername): Debug: http-client: All requests finished Apr 17 21:44:56 imap(MyUsername): Debug: http-client: peer [::1]:8983: No requests to service for this peer (1 connections exist) Apr 17 21:44:56 imap(MyUsername): Debug: http-client: conn [::1]:8983 [0]: No more requests queued; going idle (timeout = 5000 msecs)

kadafax＠gmail.com

19 Apr 19 Apr

11:30 a.m.

I think I'm progressing: if I create a brand new user, send her an email, it will not be indexed right away. I need to doveadm rescan / index the Inbox before. At the end of this email, a dovecot's debug log snipset (grep'ed on this user). In it I can read: "Debug: fts: Indexes disabled for namespace '' Can this be a problem ?

Thanks

doveconf -n output: # 2.2.34 (874deae): /usr/local/etc/dovecot/dovecot.conf # Pigeonhole version 0.4.22 (22940fb7) # OS: Linux 3.10.0-693.17.1.el7.x86_64 x86_64 CentOS Linux release 7.4.1708 (Core) ext4 # Hostname: mailServer.myDomain auth_master_user_separator = * auth_socket_path = /var/run/dovecot/auth-userdb auth_username_chars = abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ01234567890.-_@* auth_verbose = yes base_dir = /var/run/dovecot/ debug_log_path = /var/log/dovecot-debug.log default_client_limit = 5120 default_process_limit = 1024 default_vsz_limit = 2 G hostname = myDomain info_log_path = /var/log/dovecot.log lda_mailbox_autocreate = yes lda_mailbox_autosubscribe = yes listen = * log_path = /var/log/dovecot-error.log login_greeting = Welcome login_log_format_elements = user=<%u> method=%m rip=%r lip=%l mpid=%e %c mail_gid = vmail mail_location = maildir:/var/vmail/%Ln mail_plugins = " quota stats fts fts_solr" mail_uid = vmail 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 index ihave duplicate mime foreverypart extracttext namespace { inbox = yes location = maildir:/var/vmail/%Ln mailbox Drafts { auto = subscribe special_use = \Drafts } mailbox Sent { auto = subscribe special_use = \Sent } mailbox "Sent Messages" { special_use = \Sent } mailbox Trash { auto = subscribe special_use = \Trash } prefix = type = private } passdb { args = /usr/local/etc/dovecot/deny-users deny = yes driver = passwd-file } passdb { args = /usr/local/etc/dovecot/master-users driver = passwd-file master = yes } passdb { args = /usr/local/etc/dovecot/dovecot-ldap.conf.ext driver = ldap } plugin { fts = solr fts_autoindex = yes fts_solr = url=http://localhost:8983/solr/dovecot/ break-imap-search quota = maildir:User quota quota_rule = *:storage=20GB quota_rule2 = Trash:storage=+200M quota_warning = storage=95%% quota-warning 95 %u quota_warning2 = storage=80%% quota-warning 80 %u sieve = /var/sieve-scripts/%Ln.sieve sieve_dir = /var/vmail/%Ln/home/sieve stats_command_min_time = 1 mins stats_domain_min_time = 12 hours stats_ip_min_time = 12 hours stats_memory_limit = 16 M stats_refresh = 30 secs stats_session_min_time = 15 mins stats_track_cmds = yes stats_user_min_time = 1 hours } postmaster_address = postmaster@myDomain protocols = imap pop3 sieve sieve service auth-worker { user = root } service auth { unix_listener /var/spool/postfix/private/auth { mode = 0777 user = vmail } } service imap-login { inet_listener imaps { port = 993 ssl = yes } process_min_avail = 10 } service imap { process_limit = 2128 } service managesieve-login { inet_listener sieve { port = 4190 } } service pop3-login { inet_listener pop3s { port = 995 ssl = yes } } service quota-warning { executable = script /usr/local/bin/quota-warning.sh user = nobody } service stats { fifo_listener stats-mail { mode = 0600 user = vmail } } ssl_cert =

DEBUG LOG: Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib90_sieve_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: auth USER input: NewUser@myDomain uid=992 gid=989 home=/var/vmail/NewUser/home Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser@myDomain Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser@myDomain Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:26 lda(NewUser@myDomain): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 raw mail user Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 raw mail user Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:26 lda(NewUser@myDomain): Debug: none: root=, index=, indexpvt=, control=, inbox=, alt= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: fts: Indexes disabled for namespace '' Apr 19 10:23:26 lda(NewUser@myDomain): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Destination address: NewUser@myDomain (source: -a parameter) Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: Pigeonhole version 0.4.22 (22940fb7) initializing Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: include: sieve_global is not set; it is currently not possible to include :global' scripts. Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: file storage: Storage path /var/vmail/NewUser/home/sieve' not found Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: file storage: Using Sieve script path: /var/sieve-scripts/NewUser.sieve Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: file storage: Storage path `/var/sieve-scripts/NewUser.sieve' not found Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: storage: No default script location configured Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: User has no personal script Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: No scripts to execute: reverting to default delivery. Apr 19 10:23:26 lda(NewUser@myDomain): Debug: INBOX: Mailbox opened because: lib-lda delivery Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Mailbox stdin: Opened mail UID=1 because: copying Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: auth USER input: NewUser@myDomain uid=992 gid=989 home=/var/vmail/NewUser/home Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser@myDomain Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser@myDomain Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: INBOX: Mailbox opened because: indexing Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Mailbox INBOX: Opened mail UID=6 because: prefetch Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Mailbox INBOX: Opened mail UID=6 because: access Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Mailbox INBOX: Opened mail UID=6 because: fts indexing Apr 19 10:23:50 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:50 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:50 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:50 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:50 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:50 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:50 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:50 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:50 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:50 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:50 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:52 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:52 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:52 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:52 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:52 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:52 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:52 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:52 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:52 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:52 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:52 imap(NewUser): Debug: INBOX: Mailbox opened because: STATUS Apr 19 10:23:52 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:53 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:53 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:53 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:53 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:53 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:53 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:53 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:53 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:53 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:53 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:53 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:53 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=3 because: prefetch Apr 19 10:23:53 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=3 because: access Apr 19 10:23:53 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=3 because: MIME part Apr 19 10:23:54 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:54 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:54 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:54 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:54 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:54 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:54 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:54 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:54 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:54 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:54 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:54 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=4 because: prefetch Apr 19 10:23:54 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=4 because: access Apr 19 10:23:54 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=4 because: MIME part Apr 19 10:23:55 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:55 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:55 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:55 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:55 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:55 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=5 because: prefetch Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=5 because: access Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=5 because: MIME part Apr 19 10:23:55 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:55 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:55 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:55 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:55 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:55 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=6 because: prefetch Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=6 because: access Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=6 because: MIME part Apr 19 10:23:57 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:57 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:57 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:57 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:57 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:57 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:57 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:57 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:57 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:57 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:57 imap(NewUser): Debug: INBOX: Mailbox opened because: STATUS Apr 19 10:23:57 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:59 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:59 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:59 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:59 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:59 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:59 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:59 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:59 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:59 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:59 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:59 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:24:07 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:24:07 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:24:07 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:24:07 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:24:07 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:24:07 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:24:07 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:24:07 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:24:07 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:24:07 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:24:07 imap(NewUser): Debug: INBOX: Mailbox opened because: STATUS Apr 19 10:24:07 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT

kadafax＠gmail.com

4 May 4 May

12:47 p.m.

Bump. Can someone from dovecot dev team please respond something to my problem ? I've provided all the possible information, and if not please tell me. Or do you only provide support for your own FTS solution bundled with the paid version of dovecot-pro ? If so, you should clearly state it in the wiki.

Thanks

Le 19/04/2018 à 10:30, kadafax@gmail.com a écrit :

...

I think I'm progressing: if I create a brand new user, send her an email, it will not be indexed right away. I need to doveadm rescan / index the Inbox before. At the end of this email, a dovecot's debug log snipset (grep'ed on this user). In it I can read: "Debug: fts: Indexes disabled for namespace '' Can this be a problem ?

Thanks

doveconf -n output: # 2.2.34 (874deae): /usr/local/etc/dovecot/dovecot.conf # Pigeonhole version 0.4.22 (22940fb7) # OS: Linux 3.10.0-693.17.1.el7.x86_64 x86_64 CentOS Linux release 7.4.1708 (Core) ext4 # Hostname: mailServer.myDomain auth_master_user_separator = * auth_socket_path = /var/run/dovecot/auth-userdb auth_username_chars = abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ01234567890.-_@* auth_verbose = yes base_dir = /var/run/dovecot/ debug_log_path = /var/log/dovecot-debug.log default_client_limit = 5120 default_process_limit = 1024 default_vsz_limit = 2 G hostname = myDomain info_log_path = /var/log/dovecot.log lda_mailbox_autocreate = yes lda_mailbox_autosubscribe = yes listen = * log_path = /var/log/dovecot-error.log login_greeting = Welcome login_log_format_elements = user=<%u> method=%m rip=%r lip=%l mpid=%e %c mail_gid = vmail mail_location = maildir:/var/vmail/%Ln mail_plugins = " quota stats fts fts_solr" mail_uid = vmail 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 index ihave duplicate mime foreverypart extracttext namespace { inbox = yes location = maildir:/var/vmail/%Ln mailbox Drafts { auto = subscribe special_use = \Drafts } mailbox Sent { auto = subscribe special_use = \Sent } mailbox "Sent Messages" { special_use = \Sent } mailbox Trash { auto = subscribe special_use = \Trash } prefix = type = private } passdb { args = /usr/local/etc/dovecot/deny-users deny = yes driver = passwd-file } passdb { args = /usr/local/etc/dovecot/master-users driver = passwd-file master = yes } passdb { args = /usr/local/etc/dovecot/dovecot-ldap.conf.ext driver = ldap } plugin { fts = solr fts_autoindex = yes fts_solr = url=http://localhost:8983/solr/dovecot/ break-imap-search quota = maildir:User quota quota_rule = *:storage=20GB quota_rule2 = Trash:storage=+200M quota_warning = storage=95%% quota-warning 95 %u quota_warning2 = storage=80%% quota-warning 80 %u sieve = /var/sieve-scripts/%Ln.sieve sieve_dir = /var/vmail/%Ln/home/sieve stats_command_min_time = 1 mins stats_domain_min_time = 12 hours stats_ip_min_time = 12 hours stats_memory_limit = 16 M stats_refresh = 30 secs stats_session_min_time = 15 mins stats_track_cmds = yes stats_user_min_time = 1 hours } postmaster_address = postmaster@myDomain protocols = imap pop3 sieve sieve service auth-worker { user = root } service auth { unix_listener /var/spool/postfix/private/auth { mode = 0777 user = vmail } } service imap-login { inet_listener imaps { port = 993 ssl = yes } process_min_avail = 10 } service imap { process_limit = 2128 } service managesieve-login { inet_listener sieve { port = 4190 } } service pop3-login { inet_listener pop3s { port = 995 ssl = yes } } service quota-warning { executable = script /usr/local/bin/quota-warning.sh user = nobody } service stats { fifo_listener stats-mail { mode = 0600 user = vmail } } ssl_cert =
DEBUG LOG: Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib90_sieve_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: auth USER input: NewUser@myDomain uid=992 gid=989 home=/var/vmail/NewUser/home Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser@myDomain Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser@myDomain Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:26 lda(NewUser@myDomain): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 raw mail user Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 raw mail user Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:26 lda(NewUser@myDomain): Debug: none: root=, index=, indexpvt=, control=, inbox=, alt= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: fts: Indexes disabled for namespace '' Apr 19 10:23:26 lda(NewUser@myDomain): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Destination address: NewUser@myDomain (source: -a parameter) Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: Pigeonhole version 0.4.22 (22940fb7) initializing Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: include: sieve_global is not set; it is currently not possible to include :global' scripts. Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: file storage: Storage path /var/vmail/NewUser/home/sieve' not found Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: file storage: Using Sieve script path: /var/sieve-scripts/NewUser.sieve Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: file storage: Storage path `/var/sieve-scripts/NewUser.sieve' not found Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: storage: No default script location configured Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: User has no personal script Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: No scripts to execute: reverting to default delivery. Apr 19 10:23:26 lda(NewUser@myDomain): Debug: INBOX: Mailbox opened because: lib-lda delivery Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Mailbox stdin: Opened mail UID=1 because: copying Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: auth USER input: NewUser@myDomain uid=992 gid=989 home=/var/vmail/NewUser/home Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser@myDomain Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser@myDomain Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: INBOX: Mailbox opened because: indexing Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Mailbox INBOX: Opened mail UID=6 because: prefetch Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Mailbox INBOX: Opened mail UID=6 because: access Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Mailbox INBOX: Opened mail UID=6 because: fts indexing Apr 19 10:23:50 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:50 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:50 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:50 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:50 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:50 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:50 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:50 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:50 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:50 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:50 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:52 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:52 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:52 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:52 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:52 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:52 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:52 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:52 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:52 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:52 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:52 imap(NewUser): Debug: INBOX: Mailbox opened because: STATUS Apr 19 10:23:52 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:53 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:53 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:53 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:53 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:53 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:53 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:53 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:53 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:53 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:53 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:53 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:53 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=3 because: prefetch Apr 19 10:23:53 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=3 because: access Apr 19 10:23:53 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=3 because: MIME part Apr 19 10:23:54 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:54 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:54 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:54 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:54 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:54 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:54 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:54 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:54 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:54 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:54 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:54 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=4 because: prefetch Apr 19 10:23:54 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=4 because: access Apr 19 10:23:54 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=4 because: MIME part Apr 19 10:23:55 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:55 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:55 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:55 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:55 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:55 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=5 because: prefetch Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=5 because: access Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=5 because: MIME part Apr 19 10:23:55 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:55 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:55 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:55 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:55 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:55 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=6 because: prefetch Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=6 because: access Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=6 because: MIME part Apr 19 10:23:57 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:57 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:57 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:57 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:57 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:57 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:57 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:57 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:57 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:57 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:57 imap(NewUser): Debug: INBOX: Mailbox opened because: STATUS Apr 19 10:23:57 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:59 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:59 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:59 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:59 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:59 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:59 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:59 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:59 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:59 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:59 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:59 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:24:07 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:24:07 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:24:07 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:24:07 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:24:07 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:24:07 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:24:07 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:24:07 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:24:07 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:24:07 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:24:07 imap(NewUser): Debug: INBOX: Mailbox opened because: STATUS Apr 19 10:24:07 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT

Aki Tuomi

21 May 21 May

12:46 p.m.

That message is coming from the temporary raw user, not from the actual user.

How did you verify that the FTS indexes are not created or updated automatically?

Aki

On 04.05.2018 12:47, kadafax@gmail.com wrote:

...

Bump. Can someone from dovecot dev team please respond something to my problem ? I've provided all the possible information, and if not please tell me. Or do you only provide support for your own FTS solution bundled with the paid version of dovecot-pro ? If so, you should clearly state it in the wiki.

Thanks

Le 19/04/2018 à 10:30, kadafax@gmail.com a écrit :

...
I think I'm progressing: if I create a brand new user, send her an email, it will not be indexed right away. I need to doveadm rescan / index the Inbox before. At the end of this email, a dovecot's debug log snipset (grep'ed on this user). In it I can read: "Debug: fts: Indexes disabled for namespace '' Can this be a problem ?

Thanks

doveconf -n output: # 2.2.34 (874deae): /usr/local/etc/dovecot/dovecot.conf # Pigeonhole version 0.4.22 (22940fb7) # OS: Linux 3.10.0-693.17.1.el7.x86_64 x86_64 CentOS Linux release 7.4.1708 (Core) ext4 # Hostname: mailServer.myDomain auth_master_user_separator = * auth_socket_path = /var/run/dovecot/auth-userdb auth_username_chars = abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ01234567890.-_@* auth_verbose = yes base_dir = /var/run/dovecot/ debug_log_path = /var/log/dovecot-debug.log default_client_limit = 5120 default_process_limit = 1024 default_vsz_limit = 2 G hostname = myDomain info_log_path = /var/log/dovecot.log lda_mailbox_autocreate = yes lda_mailbox_autosubscribe = yes listen = * log_path = /var/log/dovecot-error.log login_greeting = Welcome login_log_format_elements = user=<%u> method=%m rip=%r lip=%l mpid=%e %c mail_gid = vmail mail_location = maildir:/var/vmail/%Ln mail_plugins = " quota stats fts fts_solr" mail_uid = vmail 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 index ihave duplicate mime foreverypart extracttext namespace { inbox = yes location = maildir:/var/vmail/%Ln mailbox Drafts { auto = subscribe special_use = \Drafts } mailbox Sent { auto = subscribe special_use = \Sent } mailbox "Sent Messages" { special_use = \Sent } mailbox Trash { auto = subscribe special_use = \Trash } prefix = type = private } passdb { args = /usr/local/etc/dovecot/deny-users deny = yes driver = passwd-file } passdb { args = /usr/local/etc/dovecot/master-users driver = passwd-file master = yes } passdb { args = /usr/local/etc/dovecot/dovecot-ldap.conf.ext driver = ldap } plugin { fts = solr fts_autoindex = yes fts_solr = url=http://localhost:8983/solr/dovecot/ break-imap-search quota = maildir:User quota quota_rule = *:storage=20GB quota_rule2 = Trash:storage=+200M quota_warning = storage=95%% quota-warning 95 %u quota_warning2 = storage=80%% quota-warning 80 %u sieve = /var/sieve-scripts/%Ln.sieve sieve_dir = /var/vmail/%Ln/home/sieve stats_command_min_time = 1 mins stats_domain_min_time = 12 hours stats_ip_min_time = 12 hours stats_memory_limit = 16 M stats_refresh = 30 secs stats_session_min_time = 15 mins stats_track_cmds = yes stats_user_min_time = 1 hours } postmaster_address = postmaster@myDomain protocols = imap pop3 sieve sieve service auth-worker { user = root } service auth { unix_listener /var/spool/postfix/private/auth { mode = 0777 user = vmail } } service imap-login { inet_listener imaps { port = 993 ssl = yes } process_min_avail = 10 } service imap { process_limit = 2128 } service managesieve-login { inet_listener sieve { port = 4190 } } service pop3-login { inet_listener pop3s { port = 995 ssl = yes } } service quota-warning { executable = script /usr/local/bin/quota-warning.sh user = nobody } service stats { fifo_listener stats-mail { mode = 0600 user = vmail } } ssl_cert =
DEBUG LOG: Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib90_sieve_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: auth USER input: NewUser@myDomain uid=992 gid=989 home=/var/vmail/NewUser/home Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser@myDomain Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser@myDomain Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:26 lda(NewUser@myDomain): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 raw mail user Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 raw mail user Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:26 lda(NewUser@myDomain): Debug: none: root=, index=, indexpvt=, control=, inbox=, alt= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: fts: Indexes disabled for namespace '' Apr 19 10:23:26 lda(NewUser@myDomain): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Destination address: NewUser@myDomain (source: -a parameter) Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: Pigeonhole version 0.4.22 (22940fb7) initializing Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: include: sieve_global is not set; it is currently not possible to include :global' scripts. Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: file storage: Storage path /var/vmail/NewUser/home/sieve' not found Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: file storage: Using Sieve script path: /var/sieve-scripts/NewUser.sieve Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: file storage: Storage path `/var/sieve-scripts/NewUser.sieve' not found Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: storage: No default script location configured Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: User has no personal script Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: No scripts to execute: reverting to default delivery. Apr 19 10:23:26 lda(NewUser@myDomain): Debug: INBOX: Mailbox opened because: lib-lda delivery Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Mailbox stdin: Opened mail UID=1 because: copying Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: auth USER input: NewUser@myDomain uid=992 gid=989 home=/var/vmail/NewUser/home Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser@myDomain Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser@myDomain Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: INBOX: Mailbox opened because: indexing Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Mailbox INBOX: Opened mail UID=6 because: prefetch Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Mailbox INBOX: Opened mail UID=6 because: access Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Mailbox INBOX: Opened mail UID=6 because: fts indexing Apr 19 10:23:50 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:50 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:50 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:50 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:50 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:50 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:50 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:50 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:50 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:50 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:50 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:52 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:52 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:52 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:52 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:52 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:52 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:52 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:52 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:52 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:52 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:52 imap(NewUser): Debug: INBOX: Mailbox opened because: STATUS Apr 19 10:23:52 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:53 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:53 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:53 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:53 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:53 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:53 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:53 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:53 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:53 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:53 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:53 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:53 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=3 because: prefetch Apr 19 10:23:53 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=3 because: access Apr 19 10:23:53 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=3 because: MIME part Apr 19 10:23:54 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:54 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:54 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:54 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:54 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:54 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:54 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:54 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:54 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:54 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:54 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:54 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=4 because: prefetch Apr 19 10:23:54 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=4 because: access Apr 19 10:23:54 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=4 because: MIME part Apr 19 10:23:55 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:55 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:55 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:55 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:55 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:55 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=5 because: prefetch Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=5 because: access Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=5 because: MIME part Apr 19 10:23:55 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:55 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:55 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:55 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:55 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:55 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=6 because: prefetch Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=6 because: access Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=6 because: MIME part Apr 19 10:23:57 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:57 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:57 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:57 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:57 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:57 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:57 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:57 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:57 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:57 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:57 imap(NewUser): Debug: INBOX: Mailbox opened because: STATUS Apr 19 10:23:57 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:59 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:59 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:59 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:59 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:59 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:59 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:59 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:59 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:59 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:59 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:59 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:24:07 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:24:07 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:24:07 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:24:07 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:24:07 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:24:07 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:24:07 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:24:07 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:24:07 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:24:07 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:24:07 imap(NewUser): Debug: INBOX: Mailbox opened because: STATUS Apr 19 10:24:07 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT

kadafax＠gmail.com

1:31 p.m.

Le 21/05/2018 à 11:46, Aki Tuomi a écrit :

...

That message is coming from the temporary raw user, not from the actual user.

How did you verify that the FTS indexes are not created or updated automatically?

I send an email containing a specific strings ('thahB8ea' in that case) and search agains it: # doveadm search -u username mailbox INBOX body thahB8ea --> No result (same with integrated search on roundcube)

Then I force a rescan, followed by the same search: # doveadm fts rescan -u username # doveadm search -u username mailbox INBOX body thahB8ea (Long waiting time) --> e09cce0283e8695ab760000002deed92 29048

So I conclude that autoindexing is not working.

Thanks, kfx

...

Aki

On 04.05.2018 12:47, kadafax@gmail.com wrote:

...
Bump. Can someone from dovecot dev team please respond something to my problem ? I've provided all the possible information, and if not please tell me. Or do you only provide support for your own FTS solution bundled with the paid version of dovecot-pro ? If so, you should clearly state it in the wiki.

Thanks

Le 19/04/2018 à 10:30, kadafax@gmail.com a écrit :

...
I think I'm progressing: if I create a brand new user, send her an email, it will not be indexed right away. I need to doveadm rescan / index the Inbox before. At the end of this email, a dovecot's debug log snipset (grep'ed on this user). In it I can read: "Debug: fts: Indexes disabled for namespace '' Can this be a problem ?

Thanks

doveconf -n output: # 2.2.34 (874deae): /usr/local/etc/dovecot/dovecot.conf # Pigeonhole version 0.4.22 (22940fb7) # OS: Linux 3.10.0-693.17.1.el7.x86_64 x86_64 CentOS Linux release 7.4.1708 (Core) ext4 # Hostname: mailServer.myDomain auth_master_user_separator = * auth_socket_path = /var/run/dovecot/auth-userdb auth_username_chars = abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ01234567890.-_@* auth_verbose = yes base_dir = /var/run/dovecot/ debug_log_path = /var/log/dovecot-debug.log default_client_limit = 5120 default_process_limit = 1024 default_vsz_limit = 2 G hostname = myDomain info_log_path = /var/log/dovecot.log lda_mailbox_autocreate = yes lda_mailbox_autosubscribe = yes listen = * log_path = /var/log/dovecot-error.log login_greeting = Welcome login_log_format_elements = user=<%u> method=%m rip=%r lip=%l mpid=%e %c mail_gid = vmail mail_location = maildir:/var/vmail/%Ln mail_plugins = " quota stats fts fts_solr" mail_uid = vmail 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 index ihave duplicate mime foreverypart extracttext namespace { inbox = yes location = maildir:/var/vmail/%Ln mailbox Drafts { auto = subscribe special_use = \Drafts } mailbox Sent { auto = subscribe special_use = \Sent } mailbox "Sent Messages" { special_use = \Sent } mailbox Trash { auto = subscribe special_use = \Trash } prefix = type = private } passdb { args = /usr/local/etc/dovecot/deny-users deny = yes driver = passwd-file } passdb { args = /usr/local/etc/dovecot/master-users driver = passwd-file master = yes } passdb { args = /usr/local/etc/dovecot/dovecot-ldap.conf.ext driver = ldap } plugin { fts = solr fts_autoindex = yes fts_solr = url=http://localhost:8983/solr/dovecot/ break-imap-search quota = maildir:User quota quota_rule = *:storage=20GB quota_rule2 = Trash:storage=+200M quota_warning = storage=95%% quota-warning 95 %u quota_warning2 = storage=80%% quota-warning 80 %u sieve = /var/sieve-scripts/%Ln.sieve sieve_dir = /var/vmail/%Ln/home/sieve stats_command_min_time = 1 mins stats_domain_min_time = 12 hours stats_ip_min_time = 12 hours stats_memory_limit = 16 M stats_refresh = 30 secs stats_session_min_time = 15 mins stats_track_cmds = yes stats_user_min_time = 1 hours } postmaster_address = postmaster@myDomain protocols = imap pop3 sieve sieve service auth-worker { user = root } service auth { unix_listener /var/spool/postfix/private/auth { mode = 0777 user = vmail } } service imap-login { inet_listener imaps { port = 993 ssl = yes } process_min_avail = 10 } service imap { process_limit = 2128 } service managesieve-login { inet_listener sieve { port = 4190 } } service pop3-login { inet_listener pop3s { port = 995 ssl = yes } } service quota-warning { executable = script /usr/local/bin/quota-warning.sh user = nobody } service stats { fifo_listener stats-mail { mode = 0600 user = vmail } } ssl_cert =
DEBUG LOG: Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib90_sieve_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: auth USER input: NewUser@myDomain uid=992 gid=989 home=/var/vmail/NewUser/home Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser@myDomain Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser@myDomain Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:26 lda(NewUser@myDomain): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 raw mail user Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 raw mail user Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:26 lda(NewUser@myDomain): Debug: none: root=, index=, indexpvt=, control=, inbox=, alt= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: fts: Indexes disabled for namespace '' Apr 19 10:23:26 lda(NewUser@myDomain): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Destination address: NewUser@myDomain (source: -a parameter) Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: Pigeonhole version 0.4.22 (22940fb7) initializing Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: include: sieve_global is not set; it is currently not possible to include :global' scripts. Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: file storage: Storage path /var/vmail/NewUser/home/sieve' not found Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: file storage: Using Sieve script path: /var/sieve-scripts/NewUser.sieve Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: file storage: Storage path `/var/sieve-scripts/NewUser.sieve' not found Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: storage: No default script location configured Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: User has no personal script Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: No scripts to execute: reverting to default delivery. Apr 19 10:23:26 lda(NewUser@myDomain): Debug: INBOX: Mailbox opened because: lib-lda delivery Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Mailbox stdin: Opened mail UID=1 because: copying Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: auth USER input: NewUser@myDomain uid=992 gid=989 home=/var/vmail/NewUser/home Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser@myDomain Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser@myDomain Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: INBOX: Mailbox opened because: indexing Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Mailbox INBOX: Opened mail UID=6 because: prefetch Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Mailbox INBOX: Opened mail UID=6 because: access Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Mailbox INBOX: Opened mail UID=6 because: fts indexing Apr 19 10:23:50 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:50 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:50 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:50 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:50 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:50 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:50 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:50 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:50 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:50 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:50 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:52 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:52 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:52 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:52 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:52 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:52 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:52 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:52 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:52 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:52 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:52 imap(NewUser): Debug: INBOX: Mailbox opened because: STATUS Apr 19 10:23:52 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:53 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:53 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:53 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:53 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:53 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:53 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:53 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:53 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:53 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:53 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:53 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:53 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=3 because: prefetch Apr 19 10:23:53 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=3 because: access Apr 19 10:23:53 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=3 because: MIME part Apr 19 10:23:54 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:54 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:54 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:54 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:54 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:54 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:54 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:54 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:54 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:54 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:54 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:54 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=4 because: prefetch Apr 19 10:23:54 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=4 because: access Apr 19 10:23:54 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=4 because: MIME part Apr 19 10:23:55 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:55 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:55 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:55 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:55 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:55 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=5 because: prefetch Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=5 because: access Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=5 because: MIME part Apr 19 10:23:55 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:55 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:55 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:55 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:55 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:55 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=6 because: prefetch Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=6 because: access Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=6 because: MIME part Apr 19 10:23:57 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:57 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:57 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:57 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:57 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:57 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:57 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:57 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:57 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:57 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:57 imap(NewUser): Debug: INBOX: Mailbox opened because: STATUS Apr 19 10:23:57 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:59 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:59 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:59 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:59 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:59 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:59 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:59 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:59 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:59 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:59 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:59 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:24:07 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:24:07 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:24:07 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:24:07 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:24:07 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:24:07 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:24:07 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:24:07 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:24:07 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:24:07 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:24:07 imap(NewUser): Debug: INBOX: Mailbox opened because: STATUS Apr 19 10:24:07 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT

Aki Tuomi

1:38 p.m.

can you try turning on pluign { fts_enforced = yes } and repeat your test?

Aki

On 21.05.2018 13:31, kadafax@gmail.com wrote:

...

Le 21/05/2018 à 11:46, Aki Tuomi a écrit :

...
That message is coming from the temporary raw user, not from the actual user.

How did you verify that the FTS indexes are not created or updated automatically? I send an email containing a specific strings ('thahB8ea' in that case) and search agains it: # doveadm search -u username mailbox INBOX body thahB8ea --> No result (same with integrated search on roundcube)

Then I force a rescan, followed by the same search: # doveadm fts rescan -u username # doveadm search -u username mailbox INBOX body thahB8ea (Long waiting time) --> e09cce0283e8695ab760000002deed92 29048

So I conclude that autoindexing is not working.

Thanks, kfx

...
Aki

On 04.05.2018 12:47, kadafax@gmail.com wrote:

...
Bump. Can someone from dovecot dev team please respond something to my problem ? I've provided all the possible information, and if not please tell me. Or do you only provide support for your own FTS solution bundled with the paid version of dovecot-pro ? If so, you should clearly state it in the wiki.

Thanks

Le 19/04/2018 à 10:30, kadafax@gmail.com a écrit :

...
I think I'm progressing: if I create a brand new user, send her an email, it will not be indexed right away. I need to doveadm rescan / index the Inbox before. At the end of this email, a dovecot's debug log snipset (grep'ed on this user). In it I can read: "Debug: fts: Indexes disabled for namespace '' Can this be a problem ?

Thanks

doveconf -n output: # 2.2.34 (874deae): /usr/local/etc/dovecot/dovecot.conf # Pigeonhole version 0.4.22 (22940fb7) # OS: Linux 3.10.0-693.17.1.el7.x86_64 x86_64 CentOS Linux release 7.4.1708 (Core) ext4 # Hostname: mailServer.myDomain auth_master_user_separator = * auth_socket_path = /var/run/dovecot/auth-userdb auth_username_chars = abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ01234567890.-_@* auth_verbose = yes base_dir = /var/run/dovecot/ debug_log_path = /var/log/dovecot-debug.log default_client_limit = 5120 default_process_limit = 1024 default_vsz_limit = 2 G hostname = myDomain info_log_path = /var/log/dovecot.log lda_mailbox_autocreate = yes lda_mailbox_autosubscribe = yes listen = * log_path = /var/log/dovecot-error.log login_greeting = Welcome login_log_format_elements = user=<%u> method=%m rip=%r lip=%l mpid=%e %c mail_gid = vmail mail_location = maildir:/var/vmail/%Ln mail_plugins = " quota stats fts fts_solr" mail_uid = vmail 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 index ihave duplicate mime foreverypart extracttext namespace { inbox = yes location = maildir:/var/vmail/%Ln mailbox Drafts { auto = subscribe special_use = \Drafts } mailbox Sent { auto = subscribe special_use = \Sent } mailbox "Sent Messages" { special_use = \Sent } mailbox Trash { auto = subscribe special_use = \Trash } prefix = type = private } passdb { args = /usr/local/etc/dovecot/deny-users deny = yes driver = passwd-file } passdb { args = /usr/local/etc/dovecot/master-users driver = passwd-file master = yes } passdb { args = /usr/local/etc/dovecot/dovecot-ldap.conf.ext driver = ldap } plugin { fts = solr fts_autoindex = yes fts_solr = url=http://localhost:8983/solr/dovecot/ break-imap-search quota = maildir:User quota quota_rule = *:storage=20GB quota_rule2 = Trash:storage=+200M quota_warning = storage=95%% quota-warning 95 %u quota_warning2 = storage=80%% quota-warning 80 %u sieve = /var/sieve-scripts/%Ln.sieve sieve_dir = /var/vmail/%Ln/home/sieve stats_command_min_time = 1 mins stats_domain_min_time = 12 hours stats_ip_min_time = 12 hours stats_memory_limit = 16 M stats_refresh = 30 secs stats_session_min_time = 15 mins stats_track_cmds = yes stats_user_min_time = 1 hours } postmaster_address = postmaster@myDomain protocols = imap pop3 sieve sieve service auth-worker { user = root } service auth { unix_listener /var/spool/postfix/private/auth { mode = 0777 user = vmail } } service imap-login { inet_listener imaps { port = 993 ssl = yes } process_min_avail = 10 } service imap { process_limit = 2128 } service managesieve-login { inet_listener sieve { port = 4190 } } service pop3-login { inet_listener pop3s { port = 995 ssl = yes } } service quota-warning { executable = script /usr/local/bin/quota-warning.sh user = nobody } service stats { fifo_listener stats-mail { mode = 0600 user = vmail } } ssl_cert =
DEBUG LOG: Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib90_sieve_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:26 lda(NewUser@myDomain): Debug: auth USER input: NewUser@myDomain uid=992 gid=989 home=/var/vmail/NewUser/home Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser@myDomain Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser@myDomain Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:26 lda(NewUser@myDomain): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 raw mail user Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 raw mail user Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:26 lda(NewUser@myDomain): Debug: none: root=, index=, indexpvt=, control=, inbox=, alt= Apr 19 10:23:26 lda(NewUser@myDomain): Debug: fts: Indexes disabled for namespace '' Apr 19 10:23:26 lda(NewUser@myDomain): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Destination address: NewUser@myDomain (source: -a parameter) Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: Pigeonhole version 0.4.22 (22940fb7) initializing Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: include: sieve_global is not set; it is currently not possible to include :global' scripts. Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: file storage: Storage path /var/vmail/NewUser/home/sieve' not found Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: file storage: Using Sieve script path: /var/sieve-scripts/NewUser.sieve Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: file storage: Storage path `/var/sieve-scripts/NewUser.sieve' not found Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: storage: No default script location configured Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: User has no personal script Apr 19 10:23:26 lda(NewUser@myDomain): Debug: sieve: No scripts to execute: reverting to default delivery. Apr 19 10:23:26 lda(NewUser@myDomain): Debug: INBOX: Mailbox opened because: lib-lda delivery Apr 19 10:23:26 lda(NewUser@myDomain): Debug: Mailbox stdin: Opened mail UID=1 because: copying Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: auth USER input: NewUser@myDomain uid=992 gid=989 home=/var/vmail/NewUser/home Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser@myDomain Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser@myDomain Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: INBOX: Mailbox opened because: indexing Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Mailbox INBOX: Opened mail UID=6 because: prefetch Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Mailbox INBOX: Opened mail UID=6 because: access Apr 19 10:23:26 indexer-worker(NewUser@myDomain): Debug: Mailbox INBOX: Opened mail UID=6 because: fts indexing Apr 19 10:23:50 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:50 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:50 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:50 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:50 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:50 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:50 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:50 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:50 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:50 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:50 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:50 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:52 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:52 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:52 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:52 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:52 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:52 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:52 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:52 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:52 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:52 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:52 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:52 imap(NewUser): Debug: INBOX: Mailbox opened because: STATUS Apr 19 10:23:52 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:53 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:53 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:53 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:53 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:53 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:53 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:53 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:53 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:53 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:53 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:53 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:53 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:53 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=3 because: prefetch Apr 19 10:23:53 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=3 because: access Apr 19 10:23:53 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=3 because: MIME part Apr 19 10:23:54 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:54 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:54 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:54 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:54 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:54 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:54 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:54 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:54 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:54 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:54 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:54 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:54 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=4 because: prefetch Apr 19 10:23:54 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=4 because: access Apr 19 10:23:54 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=4 because: MIME part Apr 19 10:23:55 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:55 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:55 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:55 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:55 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:55 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=5 because: prefetch Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=5 because: access Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=5 because: MIME part Apr 19 10:23:55 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:55 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:55 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:55 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:55 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:55 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:55 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:55 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=6 because: prefetch Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=6 because: access Apr 19 10:23:55 imap(NewUser): Debug: Mailbox INBOX: Opened mail UID=6 because: MIME part Apr 19 10:23:57 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:57 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:57 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:57 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:57 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:57 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:57 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:57 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:57 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:57 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:57 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:57 imap(NewUser): Debug: INBOX: Mailbox opened because: STATUS Apr 19 10:23:57 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:23:59 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:23:59 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:23:59 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:23:59 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:23:59 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:23:59 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:23:59 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:23:59 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:23:59 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:23:59 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:23:59 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:23:59 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT Apr 19 10:24:07 imap(NewUser): Debug: Loading modules from directory: /usr/local/lib/dovecot Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_fts_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib20_virtual_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib21_fts_solr_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib90_stats_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Module loaded: /usr/local/lib/dovecot/lib95_imap_stats_plugin.so Apr 19 10:24:07 imap(NewUser): Debug: Effective uid=992, gid=989, home=/var/vmail/NewUser/home Apr 19 10:24:07 imap(NewUser): Debug: Quota root: name=User quota backend=maildir args= Apr 19 10:24:07 imap(NewUser): Debug: Quota rule: root=User quota mailbox=* bytes=21474836480 messages=0 Apr 19 10:24:07 imap(NewUser): Debug: Quota rule: root=User quota mailbox=Trash bytes=+209715200 messages=0 Apr 19 10:24:07 imap(NewUser): Debug: Quota warning: bytes=20401094656 (95%) messages=0 reverse=no command=quota-warning 95 NewUser Apr 19 10:24:07 imap(NewUser): Debug: Quota warning: bytes=17179869184 (80%) messages=0 reverse=no command=quota-warning 80 NewUser Apr 19 10:24:07 imap(NewUser): Debug: Quota grace: root=User quota bytes=2147483648 (10%) Apr 19 10:24:07 imap(NewUser): Debug: Namespace : type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/vmail/NewUser Apr 19 10:24:07 imap(NewUser): Debug: maildir++: root=/var/vmail/NewUser, index=, indexpvt=, control=, inbox=/var/vmail/NewUser, alt= Apr 19 10:24:07 imap(NewUser): Debug: quota: quota_over_flag check: quota_over_script unset - skipping Apr 19 10:24:07 imap(NewUser): Debug: INBOX: Mailbox opened because: STATUS Apr 19 10:24:07 imap(NewUser): Debug: INBOX: Mailbox opened because: SELECT

kadafax＠gmail.com

2:11 p.m.

Le 21/05/2018 à 12:38, Aki Tuomi a écrit :

...

can you try turning on pluign { fts_enforced = yes } and repeat your test?

Same (wrong) result:

Send an email with "too6Ouka" in the body
Search against "too6Ouka":

doveadm search -u username mailbox INBOX body too6Ouka

--> No result

Force re-index:

doveadm fts rescan -u username

Search again against "too6Ouka":

doveadm search -u username mailbox INBOX body too6Ouka

--> e09cce0283e8695ab760000002deed92 29055

Don't know if relevant, but on a side note, if I send a second message with "too6Ouka" in the body, followed by: # doveadm -v index -u username Inbox --> doveadm(username): Info: INBOX: Cache is already up to date And a search against the pattern immediately return only one result instead of two.

# doveconf -n # 2.2.34 (874deae): /usr/local/etc/dovecot/dovecot.conf # Pigeonhole version 0.4.22 (22940fb7) # OS: Linux 3.10.0-862.2.3.el7.x86_64 x86_64 CentOS Linux release 7.5.1804 (Core) ext4 # Hostname: mailserver.domain auth_master_user_separator = * auth_socket_path = /var/run/dovecot/auth-userdb auth_username_chars = abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ01234567890.-_@* auth_verbose = yes base_dir = /var/run/dovecot/ debug_log_path = /var/log/dovecot-debug.log default_client_limit = 5120 default_process_limit = 1024 default_vsz_limit = 2 G hostname = domain info_log_path = /var/log/dovecot.log lda_mailbox_autocreate = yes lda_mailbox_autosubscribe = yes listen = * log_path = /var/log/dovecot-error.log login_greeting = Welcome to dovecot on mydomain login_log_format_elements = user=<%u> method=%m rip=%r lip=%l mpid=%e %c mail_gid = vmail mail_location = maildir:/var/vmail/%Ln mail_plugins = " quota stats fts fts_solr" mail_uid = vmail 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 index ihave duplicate mime foreverypart extracttext namespace { inbox = yes location = maildir:/var/vmail/%Ln mailbox Drafts { auto = subscribe special_use = \Drafts } mailbox Sent { auto = subscribe special_use = \Sent } mailbox "Sent Messages" { special_use = \Sent } mailbox Trash { auto = subscribe special_use = \Trash } prefix = type = private } passdb { args = /usr/local/etc/dovecot/deny-users deny = yes driver = passwd-file } passdb { args = /usr/local/etc/dovecot/master-users driver = passwd-file master = yes } passdb { args = /usr/local/etc/dovecot/dovecot-ldap.conf.ext driver = ldap } plugin { fts = solr fts_autoindex = yes fts_enforced = yes fts_solr = url=http://localhost:8983/solr/dovecot/ break-imap-search quota = maildir:User quota quota_rule = *:storage=20GB quota_rule2 = Trash:storage=+200M quota_warning = storage=95%% quota-warning 95 %u quota_warning2 = storage=80%% quota-warning 80 %u sieve = /var/sieve-scripts/%Ln.sieve sieve_dir = /var/vmail/%Ln/home/sieve stats_command_min_time = 1 mins stats_domain_min_time = 12 hours stats_ip_min_time = 12 hours stats_memory_limit = 16 M stats_refresh = 30 secs stats_session_min_time = 15 mins stats_track_cmds = yes stats_user_min_time = 1 hours } postmaster_address = postmaster@domain protocols = imap pop3 sieve sieve service auth-worker { user = root } service auth { unix_listener /var/spool/postfix/private/auth { mode = 0777 user = vmail } } service imap-login { inet_listener imaps { port = 993 ssl = yes } process_min_avail = 10 } service imap { process_limit = 2128 } service managesieve-login { inet_listener sieve { port = 4190 } } service pop3-login { inet_listener pop3s { port = 995 ssl = yes } } service quota-warning { executable = script /usr/local/bin/quota-warning.sh user = nobody } service stats { fifo_listener stats-mail { mode = 0600 user = vmail } } ssl_cert =

...

Aki

On 21.05.2018 13:31, kadafax@gmail.com wrote:

...
Le 21/05/2018 à 11:46, Aki Tuomi a écrit :

...
That message is coming from the temporary raw user, not from the actual user.

How did you verify that the FTS indexes are not created or updated automatically? I send an email containing a specific strings ('thahB8ea' in that case) and search agains it: # doveadm search -u username mailbox INBOX body thahB8ea --> No result (same with integrated search on roundcube)

Then I force a rescan, followed by the same search: # doveadm fts rescan -u username # doveadm search -u username mailbox INBOX body thahB8ea (Long waiting time) --> e09cce0283e8695ab760000002deed92 29048

So I conclude that autoindexing is not working.

Thanks, kfx

kfx

24 May 24 May

3:22 p.m.

After seeing that dovecot 2.2.36 was out and that:

...

fts: Fixes to indexing mails via virtual mailboxes.

fts: If mails contained NUL characters, the text around it wasn't indexed.

I've upgraded but the problem still persist :(

On 21/05/2018 13:11, kadafax@gmail.com wrote:

...

Le 21/05/2018 à 12:38, Aki Tuomi a écrit :

...
can you try turning on pluign { fts_enforced = yes } and repeat your test?

Same (wrong) result:

Send an email with "too6Ouka" in the body

Search against "too6Ouka":

doveadm search -u username mailbox INBOX body too6Ouka

--> No result

Force re-index:

doveadm fts rescan -u username

Search again against "too6Ouka":

doveadm search -u username mailbox INBOX body too6Ouka

--> e09cce0283e8695ab760000002deed92 29055

Don't know if relevant, but on a side note, if I send a second message with "too6Ouka" in the body, followed by: # doveadm -v index -u username Inbox --> doveadm(username): Info: INBOX: Cache is already up to date And a search against the pattern immediately return only one result instead of two.

# doveconf -n # 2.2.34 (874deae): /usr/local/etc/dovecot/dovecot.conf # Pigeonhole version 0.4.22 (22940fb7) # OS: Linux 3.10.0-862.2.3.el7.x86_64 x86_64 CentOS Linux release 7.5.1804 (Core) ext4 # Hostname: mailserver.domain auth_master_user_separator = * auth_socket_path = /var/run/dovecot/auth-userdb auth_username_chars = abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ01234567890.-_@* auth_verbose = yes base_dir = /var/run/dovecot/ debug_log_path = /var/log/dovecot-debug.log default_client_limit = 5120 default_process_limit = 1024 default_vsz_limit = 2 G hostname = domain info_log_path = /var/log/dovecot.log lda_mailbox_autocreate = yes lda_mailbox_autosubscribe = yes listen = * log_path = /var/log/dovecot-error.log login_greeting = Welcome to dovecot on mydomain login_log_format_elements = user=<%u> method=%m rip=%r lip=%l mpid=%e %c mail_gid = vmail mail_location = maildir:/var/vmail/%Ln mail_plugins = " quota stats fts fts_solr" mail_uid = vmail 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 index ihave duplicate mime foreverypart extracttext namespace { inbox = yes location = maildir:/var/vmail/%Ln mailbox Drafts { auto = subscribe special_use = \Drafts } mailbox Sent { auto = subscribe special_use = \Sent } mailbox "Sent Messages" { special_use = \Sent } mailbox Trash { auto = subscribe special_use = \Trash } prefix = type = private } passdb { args = /usr/local/etc/dovecot/deny-users deny = yes driver = passwd-file } passdb { args = /usr/local/etc/dovecot/master-users driver = passwd-file master = yes } passdb { args = /usr/local/etc/dovecot/dovecot-ldap.conf.ext driver = ldap } plugin { fts = solr fts_autoindex = yes fts_enforced = yes fts_solr = url=http://localhost:8983/solr/dovecot/ break-imap-search quota = maildir:User quota quota_rule = *:storage=20GB quota_rule2 = Trash:storage=+200M quota_warning = storage=95%% quota-warning 95 %u quota_warning2 = storage=80%% quota-warning 80 %u sieve = /var/sieve-scripts/%Ln.sieve sieve_dir = /var/vmail/%Ln/home/sieve stats_command_min_time = 1 mins stats_domain_min_time = 12 hours stats_ip_min_time = 12 hours stats_memory_limit = 16 M stats_refresh = 30 secs stats_session_min_time = 15 mins stats_track_cmds = yes stats_user_min_time = 1 hours } postmaster_address = postmaster@domain protocols = imap pop3 sieve sieve service auth-worker { user = root } service auth { unix_listener /var/spool/postfix/private/auth { mode = 0777 user = vmail } } service imap-login { inet_listener imaps { port = 993 ssl = yes } process_min_avail = 10 } service imap { process_limit = 2128 } service managesieve-login { inet_listener sieve { port = 4190 } } service pop3-login { inet_listener pop3s { port = 995 ssl = yes } } service quota-warning { executable = script /usr/local/bin/quota-warning.sh user = nobody } service stats { fifo_listener stats-mail { mode = 0600 user = vmail } } ssl_cert =
...
Aki

On 21.05.2018 13:31, kadafax@gmail.com wrote:

...
Le 21/05/2018 à 11:46, Aki Tuomi a écrit :

...
That message is coming from the temporary raw user, not from the actual user.

How did you verify that the FTS indexes are not created or updated automatically? I send an email containing a specific strings ('thahB8ea' in that case) and search agains it: # doveadm search -u username mailbox INBOX body thahB8ea --> No result (same with integrated search on roundcube)

Then I force a rescan, followed by the same search: # doveadm fts rescan -u username # doveadm search -u username mailbox INBOX body thahB8ea (Long waiting time) --> e09cce0283e8695ab760000002deed92 29048

So I conclude that autoindexing is not working.

Thanks, kfx

Timo Sirainen

4:33 p.m.

On 21 May 2018, at 14.11, kadafax@gmail.com wrote:

...

Le 21/05/2018 à 12:38, Aki Tuomi a écrit :

...
can you try turning on pluign { fts_enforced = yes } and repeat your test?

Same (wrong) result:

Send an email with "too6Ouka" in the body

Search against "too6Ouka":

doveadm search -u username mailbox INBOX body too6Ouka

--> No result

Force re-index:

doveadm fts rescan -u username

Search again against "too6Ouka":

doveadm search -u username mailbox INBOX body too6Ouka

--> e09cce0283e8695ab760000002deed92 29055

Don't know if relevant, but on a side note, if I send a second message with "too6Ouka" in the body, followed by: # doveadm -v index -u username Inbox --> doveadm(username): Info: INBOX: Cache is already up to date And a search against the pattern immediately return only one result instead of two.

So it looks like after "doveadm fts rescan" you can do initial indexing successfully. Afterwards the index isn't updated at all, unless you rebuild it entirely. fts_autoindex=yes appears to work as expected, because after fts rescan you did a search which automatically updated the index. So this doesn't have anything to do with fts_autoindex, just that your index updates don't seem to work at all.

What does it show in the header if you do: doveadm dump /var/vmail/username/dovecot.index

Especially what is in the "fts" header vs. "next uid" header? Does the UID in "fts" header keep changing every time you save a new mail? I suppose it will. You could also monitor (e.g. tcpdump/wireshark) the network traffic between Dovecot <-> Solr what happens when a new mail arrives. I suspect Dovecot sends it to Solr, which for whatever reason just ignores the update.

kfx

28 May 28 May

1:28 p.m.

On 24/05/2018 15:33, Timo Sirainen wrote:

...

On 21 May 2018, at 14.11, kadafax@gmail.com wrote:

...
Le 21/05/2018 à 12:38, Aki Tuomi a écrit :

...
can you try turning on pluign { fts_enforced = yes } and repeat your test?

Same (wrong) result:

Send an email with "too6Ouka" in the body

Search against "too6Ouka":

doveadm search -u username mailbox INBOX body too6Ouka

--> No result

Force re-index:

doveadm fts rescan -u username

Search again against "too6Ouka":

doveadm search -u username mailbox INBOX body too6Ouka

--> e09cce0283e8695ab760000002deed92 29055

Don't know if relevant, but on a side note, if I send a second message with "too6Ouka" in the body, followed by: # doveadm -v index -u username Inbox --> doveadm(username): Info: INBOX: Cache is already up to date And a search against the pattern immediately return only one result instead of two.

So it looks like after "doveadm fts rescan" you can do initial indexing successfully. Afterwards the index isn't updated at all, unless you rebuild it entirely. fts_autoindex=yes appears to work as expected, because after fts rescan you did a search which automatically updated the index. So this doesn't have anything to do with fts_autoindex, just that your index updates don't seem to work at all.

What does it show in the header if you do: doveadm dump /var/vmail/username/dovecot.index

Detected file type: index -- INDEX: /var/vmail/username/dovecot.index version .................. = 7.3 base header size ......... = 120 header size .............. = 640 record size .............. = 16 compat flags ............. = 1 index id ................. = 1527164208 (2018-05-24 14:16:48) flags .................... = 0 uid validity ............. = 1516890243 (2018-01-25 15:24:03) next uid ................. = 30104 messages count ........... = 17068 seen messages count ...... = 17011 deleted messages count ... = 0 first recent uid ......... = 30104 first unseen uid lowwater = 393 first deleted uid lowwater = 30087 log file seq ............. = 14 log file tail offset ..... = 28144 log file head offset ..... = 28144 log2 rotate time ......... = 1527487933 (2018-05-28 08:12:13) last temp file scan ...... = 0 (1970-01-01 01:00:00) day stamp ................ = 1527458400 (2018-05-28 00:00:00) day first uid[0] ......... = 30037 day first uid[1] ......... = 29921 day first uid[2] ......... = 29814 day first uid[3] ......... = 29669 day first uid[4] ......... = 2 day first uid[5] ......... = 0 day first uid[6] ......... = 0 day first uid[7] ......... = 0 -- Extension 0 -- name ........ = maildir hdr_size .... = 36 reset_id .... = 0 record_offset = 0 record_size . = 0 record_align = 0 header header

new_check_time .... = 2018-05-28 11:48:14
new_mtime ......... = 2018-05-28 11:47:59
new_mtime_nsecs ... = 129602175
cur_check_time .... = 2018-05-28 11:48:14
cur_mtime ......... = 2018-05-28 11:48:01
cur_mtime_nsecs.... = 277650283
uidlist_mtime ..... = 2018-05-28 11:47:58
uidlist_mtime_nsecs = 521588557
uidlist_size ...... = 1334026 -- Extension 1 -- name ........ = keywords hdr_size .... = 292 reset_id .... = 0 record_offset = 5 record_size . = 3 record_align = 1 -- Extension 2 -- name ........ = hdr-vsize hdr_size .... = 16 reset_id .... = 0 record_offset = 0 record_size . = 0 record_align = 8

highest uid . = 0
message count = 0
vsize ....... = 0 -- Extension 3 -- name ........ = cache hdr_size .... = 0 reset_id .... = 1527164233 record_offset = 8 record_size . = 4 record_align = 4 -- Extension 4 -- name ........ = vsize hdr_size .... = 0 reset_id .... = 0 record_offset = 12 record_size . = 4 record_align = 4 -- Extension 5 -- name ........ = fts hdr_size .... = 12 reset_id .... = 0 record_offset = 0 record_size . = 0 record_align = 0 header
last_indexed_uid ..... = 30103
settings_checksum .... = 0 -- Keywords -- 0 = unknown-1 1 = unknown-2 2 = unknown-5 3 = unknown-8 4 = unknown-6 5 = unknown-3 6 = unknown-7 7 = unknown-0 8 = NonJunk 9 = $Forwarded 10 = $label1 11 = $label2 12 = Junk 13 = $label3 14 = $label4

...

Especially what is in the "fts" header vs. "next uid" header? Does the UID in "fts" header keep changing every time you save a new mail? I suppose it will.

Diff between 2 emails: next uid = 30104 | next uid = 30105 last_indexed_uid = 30103 | last_indexed_uid = 30104

...

You could also monitor (e.g. tcpdump/wireshark) the network traffic between Dovecot <-> Solr what happens when a new mail arrives. I suspect Dovecot sends it to Solr, which for whatever reason just ignores the update.

################### TCPDUMP #################################### POST /solr/dovecot/update HTTP/1.1 Host: localhost:8983 Date: Mon, 28 May 2018 10:18:05 GMT Transfer-Encoding: chunked Connection: Keep-Alive Content-Type: text/xml

<add><doc><field name="uid">37581</field><field name="box">e0c58a3093235153110800003ea484a8</field><field name="user">username</field><field name="id">37581/e0c58a3093235153110800003ea484a8/username</field><field name="body">Search Pattern: Kai8oovi

-- username

</field><field name="from">"username" <username@mydomain> </field><field name="hdr">From: "username" <username@mydomain> Subject: New search test: 4 To: "username" <username@mydomain> Message-ID: <86442b87-aaad-c6bc-86f6-a74551a564c6@mydomain> Date: Mon, 28 May 2018 12:17:41 +0200 User-Agent: _ MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8; format=flowed Content-Language: fr-xx-classique+reforme1990 Content-Transfer-Encoding: 7bit </field><field name="subject">New search test: 4 </field><field name="to">"username" <username@mydomain> </field></doc></add>

######### SOLR'S RESPONSE ###############

<?xml version="1.0" encoding="UTF-8"?>

################### /TCPDUMP ####################################

Timo Sirainen

2:04 p.m.

On 28 May 2018, at 13.28, kfx kadafax@gmail.com wrote:

...

...
Especially what is in the "fts" header vs. "next uid" header? Does the UID in "fts" header keep changing every time you save a new mail? I suppose it will.

Diff between 2 emails: next uid = 30104 | next uid = 30105 last_indexed_uid = 30103 | last_indexed_uid = 30104

So Dovecot thinks it has indexed everything.

...

...
You could also monitor (e.g. tcpdump/wireshark) the network traffic between Dovecot <-> Solr what happens when a new mail arrives. I suspect Dovecot sends it to Solr, which for whatever reason just ignores the update.

################### TCPDUMP #################################### POST /solr/dovecot/update HTTP/1.1 Host: localhost:8983 Date: Mon, 28 May 2018 10:18:05 GMT Transfer-Encoding: chunked Connection: Keep-Alive Content-Type: text/xml

<add><doc><field name="uid">37581</field><field name="box">e0c58a3093235153110800003ea484a8</field><field name="user">username</field><field name="id">37581/e0c58a3093235153110800003ea484a8/username</field><field name="body">Search Pattern: Kai8oovi .. </field></doc></add>

And Dovecot sends the mail.

...

######### SOLR'S RESPONSE ###############

<?xml version="1.0" encoding="UTF-8"?>

<response>

<lst name="responseHeader"> <int name="status">0</int> <int name="QTime">0</int> </lst> </response>

And Solr receives it. Your tcpdump doesn't show <commit softCommit="true" waitSearcher="true"/> being sent though. Do you see it being sent anywhere? Does it make the mails visible if you run it yourself? Or if you run hard commit? :

curl http://<host>:8983/solr/update?commit=true

kfx

2:23 p.m.

On 28/05/2018 13:04, Timo Sirainen wrote:

...

On 28 May 2018, at 13.28, kfx mailto:kadafax@gmail.com> wrote:

...
...
Especially what is in the "fts" header vs. "next uid" header? Does the UID in "fts" header keep changing every time you save a new mail? I suppose it will.

Diff between 2 emails: next uid = 30104 | next uid = 30105 last_indexed_uid = 30103 | last_indexed_uid = 30104

So Dovecot thinks it has indexed everything.

...
...
You could also monitor (e.g. tcpdump/wireshark) the network traffic between Dovecot <-> Solr what happens when a new mail arrives. I suspect Dovecot sends it to Solr, which for whatever reason just ignores the update.

################### TCPDUMP #################################### POST /solr/dovecot/update HTTP/1.1 Host: localhost:8983 Date: Mon, 28 May 2018 10:18:05 GMT Transfer-Encoding: chunked Connection: Keep-Alive Content-Type: text/xml

<add><doc><field name="uid">37581</field><field name="box">e0c58a3093235153110800003ea484a8</field><field name="user">username</field><field name="id">37581/e0c58a3093235153110800003ea484a8/username</field><field name="body">Search Pattern: Kai8oovi .. </field></doc></add>

And Dovecot sends the mail.

...
######### SOLR'S RESPONSE ###############

<?xml version="1.0" encoding="UTF-8"?>

<response>

<lst name="responseHeader"> <int name="status">0</int> <int name="QTime">0</int> </lst> </response>

And Solr receives it. Your tcpdump doesn't show <commit softCommit="true" waitSearcher="true"/> being sent though. Do you see it being sent anywhere?

Yes:

################### TCPDUMP ################################### POST /solr/dovecot/update HTTP/1.1 Host: localhost:8983 Date: Mon, 28 May 2018 10:18:05 GMT Expect: 100-continue Content-Length: 47 Connection: Keep-Alive Content-Type: text/xml

<commit softCommit="true" waitSearcher="true"/> ################### /TCPDUMP ###################################

...

Does it make the mails visible if you run it yourself? Or if you run hard commit? :

curl http://<host>:8983/solr/update?commit=true

# curl http://127.0.0.1:8983/solr/update?commit=true

<html>

                     [0/0]

<head> <meta http-equiv="Content-Type" content="text/html;charset=utf-8"/> <title>Error 404 Not Found</title> </head> <body><h2>HTTP ERROR 404</h2> <p>Problem accessing /solr/update. Reason: <pre> Not Found</pre></p> </body> </html>

# curl http://127.0.0.1:8983/solr/dovecot/update?commit=true

<?xml version="1.0" encoding="UTF-8"?>

# doveadm search -u username mailbox INBOX body Kai8oovi ==> No result ('Kai8oovi' is the search pattern, it should returns 4 results)

kfx

2:30 p.m.

On 28/05/2018 13:23, kfx wrote:

...

On 28/05/2018 13:04, Timo Sirainen wrote:

...
On 28 May 2018, at 13.28, kfx mailto:kadafax@gmail.com> wrote:

...
...
Especially what is in the "fts" header vs. "next uid" header? Does the UID in "fts" header keep changing every time you save a new mail? I suppose it will.

Diff between 2 emails: next uid = 30104    |    next uid = 30105 last_indexed_uid = 30103    |    last_indexed_uid = 30104

So Dovecot thinks it has indexed everything.

...
...
You could also monitor (e.g. tcpdump/wireshark) the network traffic between Dovecot <-> Solr what happens when a new mail arrives. I suspect Dovecot sends it to Solr, which for whatever reason just ignores the update.

################### TCPDUMP #################################### POST /solr/dovecot/update HTTP/1.1 Host: localhost:8983 Date: Mon, 28 May 2018 10:18:05 GMT Transfer-Encoding: chunked Connection: Keep-Alive Content-Type: text/xml

<add><doc><field name="uid">37581</field><field name="box">e0c58a3093235153110800003ea484a8</field><field name="user">username</field><field name="id">37581/e0c58a3093235153110800003ea484a8/username</field><field name="body">Search Pattern: Kai8oovi .. </field></doc></add>

And Dovecot sends the mail.

...
######### SOLR'S RESPONSE ###############

<?xml version="1.0" encoding="UTF-8"?>

<response>

<lst name="responseHeader"> <int name="status">0</int> <int name="QTime">0</int> </lst> </response>

And Solr receives it. Your tcpdump doesn't show <commit softCommit="true" waitSearcher="true"/> being sent though. Do you see it being sent anywhere?

Yes:

################### TCPDUMP ################################### POST /solr/dovecot/update HTTP/1.1 Host: localhost:8983 Date: Mon, 28 May 2018 10:18:05 GMT Expect: 100-continue Content-Length: 47 Connection: Keep-Alive Content-Type: text/xml

<commit softCommit="true" waitSearcher="true"/> ################### /TCPDUMP ###################################

...
Does it make the mails visible if you run it yourself? Or if you run hard commit? :

curl http://<host>:8983/solr/update?commit=true

# curl http://127.0.0.1:8983/solr/update?commit=true

<html>

                        [0/0]

<head> <meta http-equiv="Content-Type" content="text/html;charset=utf-8"/> <title>Error 404 Not Found</title> </head> <body><h2>HTTP ERROR 404</h2> <p>Problem accessing /solr/update. Reason: <pre>    Not Found</pre></p> </body> </html>

# curl http://127.0.0.1:8983/solr/dovecot/update?commit=true

<?xml version="1.0" encoding="UTF-8"?>

<response>

<lst name="responseHeader"> <int name="status">0</int> <int name="QTime">0</int> </lst> </response>

# doveadm search -u username mailbox INBOX body Kai8oovi ==> No result ('Kai8oovi' is the search pattern, it should returns 4 results)

In the web interface of solr at http://127.0.0.1:8983/solr/#/~cores/dovecot

I can see:

...

lastModified: less than a minute ago version:1428772 numDocs:6353615 maxDoc:6356213 deletedDocs:2598

So it IS indexing :( Just below I see the "optimized:" parameter followed by an icon which seems saying that is NOT "optimized". Don't know if it's relevant. This is driving me crazy :(

kfx

29 May 29 May

3:30 p.m.

Okay I'm progressing here. If I copy an email within my MUA to another folder and then make another copy back to my Inbox, the new copied email is correctly and immediately indexed ! If I delete an email, the search result is also correct and immediate.

Is it possible that my problem come from Dovecot LDA ?

In 10-mail.conf I have: # cat dovecot/conf.d/10-mail.conf| grep -v -e "^$" -e "^#" -e "^ #" mail_location = maildir:/var/vmail/%Ln namespace { type = private location = maildir:/var/vmail/%Ln inbox = yes mailbox Trash { auto = subscribe special_use = \Trash } mailbox Drafts { auto = subscribe special_use = \Drafts } mailbox Sent { auto = subscribe # autocreate, autosubscribe special_use = \Sent } mailbox "Sent Messages" { special_use = \Sent } } mail_uid=vmail mail_gid=vmail auth_socket_path = /var/run/dovecot/auth-userdb mail_plugins = $mail_plugins quota stats fts fts_solr

And in 15-lda.conf: # cat dovecot/conf.d/15-lda.conf| grep -v -e "^$" -e "^#" -e "^ #" postmaster_address = postmaster@mydomain hostname = mydomain recipient_delimiter = + lda_mailbox_autocreate = yes lda_mailbox_autosubscribe = yes protocol lda { mail_plugins = $mail_plugins sieve virtual }

And the lda portion of "doveconf -n" become: protocol lda { mail_plugins = " quota stats fts fts_solr sieve virtual" }

Here is my postfix relevant conf:

################## MAIN.CF ############################## # postconf -n | grep dovecot dovecot_destination_recipient_limit = 1 smtpd_sasl_type = dovecot virtual_transport = dovecot #########################################################

################## MASTER.CF ############################## dovecot unix - n n - - pipe flags=DRhu user=vmail:vmail argv=/usr/local/libexec/dovecot/dovecot-lda -f ${sender} -a ${recipient} -d ${user}@${nexthop} #########################################################

On 28/05/2018 13:30, kfx wrote:

...

On 28/05/2018 13:23, kfx wrote:

...
On 28/05/2018 13:04, Timo Sirainen wrote:

...
On 28 May 2018, at 13.28, kfx mailto:kadafax@gmail.com> wrote:

...
...
Especially what is in the "fts" header vs. "next uid" header? Does the UID in "fts" header keep changing every time you save a new mail? I suppose it will.

Diff between 2 emails: next uid = 30104    |    next uid = 30105 last_indexed_uid = 30103    |    last_indexed_uid = 30104

So Dovecot thinks it has indexed everything.

...
...
You could also monitor (e.g. tcpdump/wireshark) the network traffic between Dovecot <-> Solr what happens when a new mail arrives. I suspect Dovecot sends it to Solr, which for whatever reason just ignores the update.

################### TCPDUMP #################################### POST /solr/dovecot/update HTTP/1.1 Host: localhost:8983 Date: Mon, 28 May 2018 10:18:05 GMT Transfer-Encoding: chunked Connection: Keep-Alive Content-Type: text/xml

<add><doc><field name="uid">37581</field><field name="box">e0c58a3093235153110800003ea484a8</field><field name="user">username</field><field name="id">37581/e0c58a3093235153110800003ea484a8/username</field><field name="body">Search Pattern: Kai8oovi .. </field></doc></add>

And Dovecot sends the mail.

...
######### SOLR'S RESPONSE ###############

<?xml version="1.0" encoding="UTF-8"?>

<response>

<lst name="responseHeader"> <int name="status">0</int> <int name="QTime">0</int> </lst> </response>

And Solr receives it. Your tcpdump doesn't show <commit softCommit="true" waitSearcher="true"/> being sent though. Do you see it being sent anywhere?

Yes:

################### TCPDUMP ################################### POST /solr/dovecot/update HTTP/1.1 Host: localhost:8983 Date: Mon, 28 May 2018 10:18:05 GMT Expect: 100-continue Content-Length: 47 Connection: Keep-Alive Content-Type: text/xml

<commit softCommit="true" waitSearcher="true"/> ################### /TCPDUMP ###################################

...
Does it make the mails visible if you run it yourself? Or if you run hard commit? :

curl http://<host>:8983/solr/update?commit=true

# curl http://127.0.0.1:8983/solr/update?commit=true

<html>

                         [0/0]

<head> <meta http-equiv="Content-Type" content="text/html;charset=utf-8"/> <title>Error 404 Not Found</title> </head> <body><h2>HTTP ERROR 404</h2> <p>Problem accessing /solr/update. Reason: <pre>    Not Found</pre></p> </body> </html>

# curl http://127.0.0.1:8983/solr/dovecot/update?commit=true

<?xml version="1.0" encoding="UTF-8"?>

<response>

<lst name="responseHeader">    <int name="status">0</int>    <int name="QTime">0</int> </lst> </response>

# doveadm search -u username mailbox INBOX body Kai8oovi ==> No result ('Kai8oovi' is the search pattern, it should returns 4 results)

In the web interface of solr at http://127.0.0.1:8983/solr/#/~cores/dovecot

I can see:

...
lastModified: less than a minute ago version:1428772 numDocs:6353615 maxDoc:6356213 deletedDocs:2598

So it IS indexing :( Just below I see the "optimized:" parameter followed by an icon which seems saying that is NOT "optimized". Don't know if it's relevant. This is driving me crazy :(

2318

Age (days ago)

2360

Last active (days ago)

List overview

14 comments

4 participants

participants (4)

Aki Tuomi
kadafax＠gmail.com
kfx
Timo Sirainen

Bug: Dovecot index loosing sync with FTS despite "fts_autoindex = yes"

doveadm search -u username mailbox INBOX body too6Ouka

doveadm fts rescan -u username

doveadm search -u username mailbox INBOX body too6Ouka

doveadm search -u username mailbox INBOX body too6Ouka

doveadm fts rescan -u username

doveadm search -u username mailbox INBOX body too6Ouka

doveadm search -u username mailbox INBOX body too6Ouka

doveadm fts rescan -u username

doveadm search -u username mailbox INBOX body too6Ouka

doveadm search -u username mailbox INBOX body too6Ouka

doveadm fts rescan -u username

doveadm search -u username mailbox INBOX body too6Ouka

tags

participants (4)