On 7/18/22 5:53 AM, Aki Tuomi wrote:
Can you provide logs & doveconf -n?
referencing prior mention of a tika ML thread,
https://dovecot.org/pipermail/dovecot/2022-July/125024.html
latest tika logs, as part of the ongoing thread there, are,
https://tika.markmail.org/message/e2oyz55l6xd234yh
here's dovecot debug log output
('mail_debug=yes', since 'log_debug=(category=fts-tika)' is not yet supported)
...
2022-07-18 06:47:43 indexer-worker(myu...@example.com)<>: Debug:
Loading modules from directory: /usr/lib64/dovecot
2022-07-18 06:47:43 indexer-worker(myu...@example.com)<>: Debug: Module
loaded: /usr/lib64/dovecot/lib01_acl_plugin.so
2022-07-18 06:47:43 indexer-worker(myu...@example.com)<>: Debug: Module
loaded: /usr/lib64/dovecot/lib20_fts_plugin.so
2022-07-18 06:47:43 indexer-worker(myu...@example.com)<>: Debug: Module
loaded: /usr/lib64/dovecot/lib20_virtual_plugin.so
2022-07-18 06:47:43 indexer-worker(myu...@example.com)<>: Debug: Module
loaded: /usr/lib64/dovecot/lib21_fts_flatcurve_plugin.so
2022-07-18 06:47:43 indexer-worker(myu...@example.com)<>: Debug:
auth-master: userdb lookup(myu...@example.com): Started userdb lookup
2022-07-18 06:47:43 indexer-worker(myu...@example.com)<>: Debug:
auth-master: conn unix:/run/dovecot/auth-userdb: Connecting
2022-07-18 06:47:43 indexer-worker(myu...@example.com)<>: Debug:
auth-master: conn unix:/run/dovecot/auth-userdb (pid=49802,uid=0): Client connected
(fd=14)
2022-07-18 06:47:43 indexer-worker(myu...@example.com)<>: Debug:
auth-master: userdb lookup(myu...@example.com): auth USER input: myu...@example.com
home=/data/vmail/example.com/myuser/Maildir uid=5000 gid=5000
quota=dirsize:storage=10240000
2022-07-18 06:47:43 indexer-worker(myu...@example.com)<>: Debug:
auth-master: userdb lookup(myu...@example.com): Finished userdb lookup
(username=myu...@example.com home=/data/vmail/example.com/myuser/Maildir uid=5000
gid=5000 quota=dirsize:storage=10240000)
2022-07-18 06:47:43 indexer-worker(myu...@example.com)<>: Debug: Added
userdb setting: plugin/quota=dirsize:storage=10240000
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: Effective uid=5000, gid=5000, home=/data/vmail/example.com/myuser/Maildir
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: shared: root=/run/dovecot/, index=, indexpvt=, control=, inbox=, alt=
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: fts: Indexes disabled for namespace 'shared/%u/'
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: acl: initializing backend with data:
vfile:/home/vmail/conf.d/example.com/acls:cache_secs=300
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: acl: acl username = myu...@example.com
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: acl: owner = 0
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: fs: root=/data/vmail/example.com/myuser/Maildir,
index=/var/vmail-index/example.com/myuser, indexpvt=,
control=/data/vmail/example.com/myuser/_control,
inbox=/data/vmail/example.com/myuser/Maildir, alt=
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: acl: initializing backend with data:
vfile:/home/vmail/conf.d/example.com/acls:cache_secs=300
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: acl: acl username = myu...@example.com
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: acl: owner = 1
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: fs: root=/data/vmail/virtual, index=, indexpvt=, control=, inbox=, alt=
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: fts: Indexes disabled for namespace 'virtual/'
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: acl: initializing backend with data:
vfile:/home/vmail/conf.d/example.com/acls:cache_secs=300
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: acl: acl username = myu...@example.com
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: acl: owner = 1
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: fts-flatcurve: Xapian library version: 1.4.19
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: fts-flatcurve(INBOX): Opened DB (RO) messages=3901 version=1 shards=2
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: fts-flatcurve(INBOX): Last UID uid=146399
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: fts-flatcurve(INBOX): Last UID uid=146399
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: fts-flatcurve(INBOX): Opened DB (RW; current.1654048601101678) messages=342
version=1
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: fts-flatcurve(INBOX): Indexing uid=146400
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: host 127.0.0.1: Host created
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: host 127.0.0.1: Host session created
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: peer 127.0.0.1:9998 (shared): Peer created
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: peer 127.0.0.1:9998: Peer pool created
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: peer 127.0.0.1:9998: Peer created
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: queue http://127.0.0.1:9998: Setting up connection to
127.0.0.1:9998 (1 requests pending)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: peer 127.0.0.1:9998: Linked queue http://127.0.0.1:9998 (1
queues linked)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: queue http://127.0.0.1:9998: Started new connection to
127.0.0.1:9998
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Submitted (requests
left=1)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Waiting for request
to finish
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: peer 127.0.0.1:9998: Creating 1 new connections to handle
requests (already 0 usable, connecting to 0, closing 0)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: peer 127.0.0.1:9998: Making new connection 1 of 1 (0 connections
exist, 0 pending)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: conn 127.0.0.1:9998 [1]: Connecting
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: conn 127.0.0.1:9998 [1]: Waiting for connect (fd=30) to finish
for max 0 msecs
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: conn 127.0.0.1:9998 [1]: HTTP connection created (1 parallel
connections exist)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: conn 127.0.0.1:9998 [1]: Client connected (fd=30)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: conn 127.0.0.1:9998 [1]: Connected
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: conn 127.0.0.1:9998 [1]: Ready for requests
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: peer 127.0.0.1:9998: Successfully connected (1 connections
exist, 0 pending)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: peer 127.0.0.1:9998: Successfully connected (1 connections
exist, 0 pending)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: peer 127.0.0.1:9998: Using 1 idle connections to handle 1
requests (1 total connections ready)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: queue http://127.0.0.1:9998: Connection to peer 127.0.0.1:9998
claimed request [Req1: PUT http://127.0.0.1:9998/tika/]
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: conn 127.0.0.1:9998 [1]: Claimed request [Req1: PUT
http://127.0.0.1:9998/tika/]
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Sent header
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Send more (sent
5569, buffered=5577)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: peer 127.0.0.1:9998: No more requests to service for this peer
(1 connections exist, 0 pending)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Waiting for request
to finish
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Send more (sent
8946, buffered=769)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Waiting for request
to finish
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Send more (sent
9588, buffered=1411)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Waiting for request
to finish
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Send more (sent
9508, buffered=1331)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Waiting for request
to finish
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Send more (sent
9568, buffered=1391)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Waiting for request
to finish
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Send more (sent
9497, buffered=1320)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Waiting for request
to finish
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Send more (sent
9653, buffered=1476)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Waiting for request
to finish
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Send more (sent
9504, buffered=1327)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Waiting for request
to finish
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Send more (sent
9603, buffered=1426)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Waiting for request
to finish
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Send more (sent
9596, buffered=1419)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Waiting for request
to finish
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Send more (sent
9202, buffered=1025)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Waiting for request
to finish
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Send more (sent
4698, buffered=4706)
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Finished sending
payload
2022-07-18 06:47:43
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Waiting for request
to finish
2022-07-18 06:47:44 lmtp(myu...@example.com)<Va3WB0461WIqwwAA+IOfAw>:
Debug: lmtp-server: conn unix:pid=49910,uid=89 [1]: rcpt myu...@example.com:
duplicate db: Cleanup
2022-07-18 06:47:44 lmtp(49962): Debug: lmtp-server: conn
unix:pid=49910,uid=89 [1]: rcpt myu...@example.com: User session is finished
2022-07-18 06:47:44 lmtp(49962): Debug: lmtp-server: conn
unix:pid=49910,uid=89 [1]: rcpt myu...@example.com: dict(file): dict destroyed
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: conn 127.0.0.1:9998 [1]: Got 422 response for request [Req1: PUT
http://127.0.0.1:9998/tika/]: Unprocessable Entity (took 345 ms + 258 ms in queue)
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: fts_tika: PUT http://127.0.0.1:9998/tika/ failed: 422 Unprocessable Entity
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Finished
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: queue http://127.0.0.1:9998: Dropping request [Req1: PUT
http://127.0.0.1:9998/tika/]
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: host 127.0.0.1: Host is idle (timeout = 100 msecs)
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: request [Req1: PUT http://127.0.0.1/tika/]: Free (requests
left=1)
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: fts-flatcurve(INBOX): Committed 1 changes to DB (RW;
current.1654048601101678) in 0.110 secs
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: fts-flatcurve: Update transaction completed in 0.551 secs
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: User session is finished
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: peer 127.0.0.1:9998: No requests to service for this peer (1
connections exist, 0 pending)
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: conn 127.0.0.1:9998 [1]: No more requests queued; going idle
(timeout = 100 msecs)
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: host 127.0.0.1: Idle host timed out
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: host 127.0.0.1: Host destroy
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: host 127.0.0.1: Host session destroy
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: queue http://127.0.0.1:9998: Destroy
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: peer 127.0.0.1:9998: Unlinked queue http://127.0.0.1:9998 (0
queues linked)
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: conn 127.0.0.1:9998 [1]: Idle connection timed out
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: conn 127.0.0.1:9998 [1]: Connection close
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: conn 127.0.0.1:9998 [1]: Connection disconnect
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: conn 127.0.0.1:9998 [1]: Disconnected: Connection closed (fd=30)
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: conn 127.0.0.1:9998 [1]: Detached peer
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: peer 127.0.0.1:9998: Lost a connection (0 queues linked, 0
connections left, 0 connections pending, 0 requests pending, 0 requests urgent)
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: conn 127.0.0.1:9998 [1]: Connection destroy
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: peer 127.0.0.1:9998: Dropping peer now
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: peer 127.0.0.1:9998: Peer close
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: peer 127.0.0.1:9998: Peer disconnect
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: peer 127.0.0.1:9998: Peer destroy
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: peer 127.0.0.1:9998: Peer pool destroy
2022-07-18 06:47:44
indexer-worker(myu...@example.com)<Wb3AB1511WIqzdAC+IOfAw:kI7WJ0861WIszdAC+IOfAw>:
Debug: http-client: peer 127.0.0.1:9998 (shared): Peer destroy
...
and
doveconf -n
-> https://pastebin.com/raw/MqdW7QHB
note that with prior/earlier versions of dovecot+tika (dunno exact yet; within
the last few weeks ...) tika scanning with fts-tika was just fine.
atm, without fts-tika enabled, i've no issues with dovecot send or receive.
