lmtp-Eroor

Previous Topic Next Topic
 
classic Classic list List threaded Threaded
7 messages Options
Reply | Threaded
Open this post in threaded view
|

lmtp-Eroor

Jakob Schürz
Hi There!

I can not find anything about this message:

dovecot[23202]: lmtp(jakob): Debug: Mailbox <lmtp DATA>: Opened mail
UID=1 because: header Message-ID (Cache file is unusable)
dovecot[23202]: lmtp(jakob): Debug: Mailbox <lmtp DATA>: Opened mail
UID=1 because: virtual size (Cache file is unusable)

And the next is:
dovecot[23202]: lmtp(jakob): rphlK01SG1qiWgAAk1Mx3g: sieve:
msgid=<[hidden email]>: stored mail into mailbox
'INBOX/TESTORDNER'

It happens when a mail gets delivered through lmtp.

My exim-Transport is this:

dovecot_lmtp:
  driver = lmtp
  socket = /var/run/dovecot/lmtp
  #maximum number of deliveries per batch, default 1
  batch_max = 200


And my dovecot-Conf is this:

# dovecot -n
# 2.2.27 (c0f36b0): /etc/dovecot/dovecot.conf
# Pigeonhole version 0.4.16 (fed8554)
# OS: Linux 4.13.0-trunk-amd64 x86_64 Debian 9.1 btrfs
auth_debug = yes
auth_mechanisms = plain login cram-md5 digest-md5
auth_socket_path = /var/run/dovecot/auth-userdb
auth_verbose = yes
first_valid_uid = 1000
imap_capability = +XDOVECOT
imap_client_workarounds = tb-extra-mailbox-sep
lda_mailbox_autocreate = yes
lda_mailbox_autosubscribe = yes
listen = *, ::1
login_trusted_networks = 127.0.0.1/8 192.168.0.0/24 192.168.1.0/24
172.17.0.0/24 172.18.0.0/24
mail_debug = yes
mail_gid = vmail
mail_home =  /var/mail/%u
mail_location =
maildir:/var/mail/%u/Maildir:LAYOUT=fs:INDEX=/var/lib/dovecot/db/indexes/Maildir/%u:INDEXPVT=/var/lib/dovecot/db/indexes/Maildir/%u:CONTROL=/var/lib/dovecot/db/control/Maildir/%u
mail_plugins = zlib quota acl listescape mail_log notify virtual
mail_privileged_group = vmail
mail_server_admin = mailto:[hidden email]
mail_shared_explicit_inbox = yes
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
mmap_disable = yes
namespace {
  hidden = no
  inbox = no
  list = children
  location =
maildir:/var/mail/mailarchiv/%u/:LAYOUT=fs:INDEX=/var/lib/dovecot/db/indexes/mailarchiv/%u:INDEXPVT=/var/lib/dovecot/db/indexes/mailarchiv/%u
  mailbox incoming {
    auto = create
  }
  mailbox outgoing {
    auto = create
  }
  prefix = Mailarchiv/
  separator = /
  subscriptions = no
  type = private
}
namespace {
  list = children
  location =
maildir:/var/mail/public/:LAYOUT=fs:INDEX=/var/lib/dovecot/db/indexes/public/%u:INDEXPVT=/var/lib/dovecot/db/indexes/public/%u
  prefix = Roseggergasse/
  separator = /
  subscriptions = no
  type = public
}
namespace Geteilt {
  hidden = no
  inbox = no
  list = children
  location =
maildir:/var/mail/%%u/Maildir:LAYOUT=fs:INDEXPVT=/var/lib/dovecot/db/indexes/shared/%u/%%u:INDEX=/var/lib/dovecot/db/indexes/shared/%u/%%u
  prefix = Geteilt/%%n/
  separator = /
  subscriptions = no
  type = shared
}
namespace Real {
  hidden = yes
  list = no
  location =
virtual:/var/mail/real:INDEX=/var/lib/dovecot/db/indexes/real/%u
  prefix = Real/
  separator = /
  subscriptions = no
}
namespace Synoptic {
  hidden = no
  list = children
  location =
virtual:/var/mail/virtual:INDEX=/var/lib/dovecot/db/indexes/virtual/%u
  mailbox INBOX/Archives {
    auto = no
    special_use = \Archive
  }
  mailbox INBOX/Drafts {
    auto = no
    special_use = \Drafts
  }
  mailbox INBOX/Entwürfe {
    auto = no
    special_use = \Drafts
  }
  mailbox INBOX/Junk {
    auto = no
    special_use = \Junk
  }
  mailbox INBOX/Spam {
    auto = no
    special_use = \Junk
  }
  mailbox Sent {
    auto = no
    special_use = \Sent
  }
  prefix = Synoptic/
  separator = /
  subscriptions = no
}
namespace inbox {
  hidden = no
  inbox = yes
  location =
  mailbox Archiv {
    auto = no
    special_use = \Archive
  }
  mailbox Archive {
    auto = no
    special_use = \Archive
  }
  mailbox Archives {
    auto = no
    special_use = \Archive
  }
  mailbox "Deleted Messages" {
    auto = no
    special_use = \Trash
  }
  mailbox Drafts {
    auto = no
    special_use = \Drafts
  }
  mailbox Entwürfe {
    auto = no
    special_use = \Drafts
  }
  mailbox "Gelöschte Elemente" {
    auto = no
    special_use = \Trash
  }
  mailbox "Gelöschte Objekte" {
    auto = no
    special_use = \Trash
  }
  mailbox Gesendet {
    auto = no
    special_use = \Sent
  }
  mailbox "Gesendete Elemente" {
    auto = no
    special_use = \Sent
  }
  mailbox "Gesendete Objekte" {
    auto = no
    special_use = \Sent
  }
  mailbox Important {
    auto = no
  }
  mailbox Junk {
    auto = subscribe
    autoexpunge = 30 days
    special_use = \Junk
  }
  mailbox Mistkübel {
    auto = no
    special_use = \Trash
  }
  mailbox Papierkorb {
    auto = no
    special_use = \Trash
  }
  mailbox Sent {
    auto = subscribe
    special_use = \Sent
  }
  mailbox "Sent Messages" {
    auto = no
    special_use = \Sent
  }
  mailbox Spam {
    auto = no
    special_use = \Junk
  }
  mailbox Synoptic/Alle {
    auto = no
    comment = All my messages
    special_use = \All
  }
  mailbox Trash {
    auto = no
    special_use = \Trash
  }
  mailbox Wichtig {
    auto = create
  }
  prefix = INBOX/
  separator = /
  subscriptions = no
  type = private
}
namespace subscriptions {
  hidden = yes
  list = no
  location =
  prefix =
  subscriptions = yes
}
passdb {
  args = scheme=CRYPT username_format=%u /etc/dovecot/users
  driver = passwd-file
}
plugin {
  acl = vfile:/etc/dovecot/dovecot-acl:cache_secs=300
  acl_shared_dict = file:/var/lib/dovecot/db/shared-mailboxes.db
  fts =  lucene
  fts_autoindex =  yes
  fts_autoindex_max_recent_msgs =  80
  fts_decoder =  decode2text.sh
  fts_index_timeout =  90
  fts_lucene =  whitespace_chars=@. normalize no_snowball
  mail_home =  /var/mail/%u
  setting_name = sieve, managedsieve
  sieve = file:/var/mail/%u/sieve/;active=/var/mail/%u/sieve/%u.sieve
}
postmaster_address = postmaster@localhost
protocols = " imap lmtp sieve pop3 sieve"
service anvil {
  unix_listener anvil-auth-penalty {
    mode = 00
  }
}
service auth {
  unix_listener auth-client {
    group = vmail
    mode = 0666
    user = vmail
  }
  unix_listener auth-userdb {
    group = vmail
    mode = 0666
    user = vmail
  }
}
service imap-login {
  inet_listener imap {
    port = 143
  }
  inet_listener imaps {
    port = 993
    ssl = yes
  }
  process_min_avail = 1
  service_count = 1
}
service imap {
  executable = imap postlogin
  process_limit = 1024
  vsz_limit = 400 M
}
service lmtp {
  user = vmail
  vsz_limit = 400 M
}
service postlogin {
  executable = script-login -d rawlog
}
ssl = required
ssl_cert = </etc/dovecot/dovecot.crt
ssl_cipher_list = ALL:!ADH:!LOW:!SSLv2:!SSLv3:!EXP:!aNULL:+HIGH:+MEDIUM
ssl_key =  # hidden, use -P to show it
ssl_protocols = TLSv1.2 TLSv1.1 TLSv1 !SSLv3
userdb {
  args = username_format=%u /etc/dovecot/users
  default_fields = home=/var/mail/%u
  driver = passwd-file
}
verbose_proctitle = yes
protocol lmtp {
  auth_username_format = %n
  mail_plugins = zlib quota acl listescape mail_log notify virtual quota
sieve acl
}
protocol lda {
  mail_plugins = zlib quota acl listescape mail_log notify virtual quota
sieve acl
}
protocol imap {
  mail_max_userip_connections = 10
  mail_plugins = zlib quota acl listescape mail_log notify virtual
imap_quota imap_acl
}



signature.asc (915 bytes) Download Attachment
Reply | Threaded
Open this post in threaded view
|

Re: lmtp-Eroor

Stephan Bosch-2
Op 11/27/2017 om 12:53 AM schreef Jakob Schürz:
> Hi There!
>
> I can not find anything about this message:
>
> dovecot[23202]: lmtp(jakob): Debug: Mailbox <lmtp DATA>: Opened mail
> UID=1 because: header Message-ID (Cache file is unusable)
> dovecot[23202]: lmtp(jakob): Debug: Mailbox <lmtp DATA>: Opened mail
> UID=1 because: virtual size (Cache file is unusable)

These are debug messages. The incoming LMTP DATA stream is a raw
message, which has no cache (hence 'Cache file is unusable'). This is
normal.

> And the next is:
> dovecot[23202]: lmtp(jakob): rphlK01SG1qiWgAAk1Mx3g: sieve:
> msgid=<[hidden email]>: stored mail into mailbox
> 'INBOX/TESTORDNER'

Your Sieve script stored the message in INBOX/TESTORDNER. Also not strange.

> It happens when a mail gets delivered through lmtp.

Any particular problem you're facing?

Regards,

Stephan.

Reply | Threaded
Open this post in threaded view
|

Re: lmtp-Eroor

Jakob Schürz
Hi!


Am 2017-11-27 um 01:35 schrieb Stephan Bosch:

> Op 11/27/2017 om 12:53 AM schreef Jakob Schürz:
>> Hi There!
>>
>> I can not find anything about this message:
>>
>> dovecot[23202]: lmtp(jakob): Debug: Mailbox <lmtp DATA>: Opened mail
>> UID=1 because: header Message-ID (Cache file is unusable)
>> dovecot[23202]: lmtp(jakob): Debug: Mailbox <lmtp DATA>: Opened mail
>> UID=1 because: virtual size (Cache file is unusable)
>
> These are debug messages. The incoming LMTP DATA stream is a raw
> message, which has no cache (hence 'Cache file is unusable'). This is
> normal.
Ok. I did a lot of debugging the last weeks for another bug. But I've
never seen this message before. So i thought it is new. But obviously I
haven't seen this, but it was there.

>
>> And the next is:
>> dovecot[23202]: lmtp(jakob): rphlK01SG1qiWgAAk1Mx3g: sieve:
>> msgid=<[hidden email]>: stored mail into mailbox
>> 'INBOX/TESTORDNER'
>
> Your Sieve script stored the message in INBOX/TESTORDNER. Also not strange>
>> It happens when a mail gets delivered through lmtp.
>
> Any particular problem you're facing?
I don't know, if this is another or the same problem.
Since i tried to find out, what is this unusable cache-file, I realized
this error-Message, sending an Email with Thunderbird (not from
commandline with mail):


Nov 27 07:35:42 aldebaran dovecot[706]: imap(jakob): Debug: Mailbox
INBOX/Sent: Opened mail UID=0 because: mail stream
Nov 27 07:35:42 aldebaran spamd[1258]: prefork: child states: II
Nov 27 07:35:42 aldebaran dovecot[706]: imap(jakob): Error: Log
synchronization error at seq=5,offset=25508 for
/var/lib/dovecot/db/indexes/Maildir/jakob/Sent/dovecot.index: Append
with UID 2431, but next_uid = 2432
Nov 27 07:35:42 aldebaran dovecot[706]: imap(jakob): Warning: fscking
index file /var/lib/dovecot/db/indexes/Maildir/jakob/Sent/dovecot.index
Nov 27 07:35:42 aldebaran dovecot[706]: imap(jakob): save:
box=INBOX/Sent, uid=2431,
msgid=<[hidden email]>, size=2693

And then i got this many times:

Nov 27 07:28:43 aldebaran dovecot[706]: imap(jakob): Debug: Mailbox
INBOX/Sent: Opened mail UID=2427 because: access
Nov 27 07:28:43 aldebaran dovecot[706]: imap(jakob): Debug: Mailbox
INBOX/Sent: Opened mail UID=2427 because: mail stream
Nov 27 07:28:43 aldebaran dovecot[706]: imap(jakob): Panic: file
mail-index.c: line 679 (mail_index_close): assertion failed:
(index->open_count > 0)
Nov 27 07:28:43 aldebaran dovecot[706]: imap(jakob): Error: Raw
backtrace: /usr/lib/dovecot/libdovecot.so.0(+0x95272) [0x7f4e03f4b272]
-> /usr/lib/dovecot/libdovecot.so.0(+0x9536d) [0x7f4e03f4b36d] ->
/usr/lib/dovecot/libdovecot.so.0(i_fatal+0) [0x7f4e03ee1951] ->
/usr/lib/dovecot/libdovecot-storage.so.0(+0xe0d18) [0x7f4e042b7d18] ->
/usr/lib/dovecot/libdovecot-storage.so.0(+0xc6f91) [0x7f4e0429df91] ->
/usr/lib/dovecot/libdovecot-storage.so.0(+0xc7023) [0x7f4e0429e023] ->
/usr/lib/dovecot/libdovecot-storage.so.0(+0xbe6c4) [0x7f4e042956c4] ->
/usr/lib/dovecot/libdovecot-storage.so.0(mailbox_free+0x19)
[0x7f4e042176b9] ->
/usr/lib/dovecot/modules/lib20_virtual_plugin.so(+0x9dac)
[0x7f4e028cddac] ->
/usr/lib/dovecot/modules/lib20_virtual_plugin.so(+0x9e59)
[0x7f4e028cde59] ->
/usr/lib/dovecot/libdovecot-storage.so.0(mailbox_close+0x1a)
[0x7f4e0421762a] ->
/usr/lib/dovecot/modules/lib20_virtual_plugin.so(+0x9e0c)
[0x7f4e028cde0c] ->
/usr/lib/dovecot/modules/lib20_virtual_plugin.so(+0x9e59)
[0x7f4e028cde59] ->
/usr/lib/dovecot/libdovecot-storage.so.0(mailbox_close+0x1a)
[0x7f4e0421762a] ->
/usr/lib/dovecot/libdovecot-storage.so.0(mailbox_free+0x13)
[0x7f4e042176b3] -> dovecot/imap [jakob 192.168.0.14 select](+0x142eb)
[0x560e9dc052eb] -> dovecot/imap [jakob 192.168.0.14
select](cmd_select_full+0x129) [0x560e9dc05449] -> dovecot/imap [jakob
192.168.0.14 select](command_exec+0xa5) [0x560e9dc0c735] -> dovecot/imap
[jakob 192.168.0.14 select](+0x199c2) [0x560e9dc0a9c2] -> dovecot/imap
[jakob 192.168.0.14 select](+0x19a4c) [0x560e9dc0aa4c] -> dovecot/imap
[jakob 192.168.0.14 select](client_handle_input+0x1b5) [0x560e9dc0ae55]
-> dovecot/imap [jakob 192.168.0.14 select](client_input+0x86)
[0x560e9dc0b3c6] ->
/usr/lib/dovecot/libdovecot.so.0(io_loop_call_io+0x52) [0x7f4e03f5fdd2]
-> /usr/lib/dovecot/libdovecot.so.0(io_loop_handler_run_internal+0x109)
[0x7f4e03f61409] ->
/usr/lib/dovecot/libdovecot.so.0(io_loop_handler_run+0x3c)
[0x7f4e03f5fe6c] -> /usr/lib/dovecot/libdovecot.so.0(io_loop_run+0x38)
[0x7f4e03f60018] ->
/usr/lib/dovecot/libdovecot.so.0(master_service_run+0x13)
[0x7f4e03ee7e93] -> dovecot/imap [jakob 192.168.0.14 select](main+0x328)
[0x560e9dbfde68]
Nov 27 07:28:43 aldebaran dovecot[706]: imap(jakob): Fatal: master:
service(imap): child 6631 killed with signal 6 (core dumps disabled)

What is this?

jakob


signature.asc (915 bytes) Download Attachment
Reply | Threaded
Open this post in threaded view
|

Re: lmtp-Eroor

Aki Tuomi-2


On 27.11.2017 08:37, Jakob Schürz wrote:

> Hi!
>
>
> Am 2017-11-27 um 01:35 schrieb Stephan Bosch:
>> Op 11/27/2017 om 12:53 AM schreef Jakob Schürz:
>>> Hi There!
>>>
>>> I can not find anything about this message:
>>>
>>> dovecot[23202]: lmtp(jakob): Debug: Mailbox <lmtp DATA>: Opened mail
>>> UID=1 because: header Message-ID (Cache file is unusable)
>>> dovecot[23202]: lmtp(jakob): Debug: Mailbox <lmtp DATA>: Opened mail
>>> UID=1 because: virtual size (Cache file is unusable)
>> These are debug messages. The incoming LMTP DATA stream is a raw
>> message, which has no cache (hence 'Cache file is unusable'). This is
>> normal.
> Ok. I did a lot of debugging the last weeks for another bug. But I've
> never seen this message before. So i thought it is new. But obviously I
> haven't seen this, but it was there.

It just means that the cache file wasn't available, because it had been
removed. It's not a serious problem.

>>> And the next is:
>>> dovecot[23202]: lmtp(jakob): rphlK01SG1qiWgAAk1Mx3g: sieve:
>>> msgid=<[hidden email]>: stored mail into mailbox
>>> 'INBOX/TESTORDNER'
>> Your Sieve script stored the message in INBOX/TESTORDNER. Also not strange>
>>> It happens when a mail gets delivered through lmtp.
>> Any particular problem you're facing?
> I don't know, if this is another or the same problem.
> Since i tried to find out, what is this unusable cache-file, I realized
> this error-Message, sending an Email with Thunderbird (not from
> commandline with mail):
>
>
> Nov 27 07:35:42 aldebaran dovecot[706]: imap(jakob): Debug: Mailbox
> INBOX/Sent: Opened mail UID=0 because: mail stream
> Nov 27 07:35:42 aldebaran spamd[1258]: prefork: child states: II
> Nov 27 07:35:42 aldebaran dovecot[706]: imap(jakob): Error: Log
> synchronization error at seq=5,offset=25508 for
> /var/lib/dovecot/db/indexes/Maildir/jakob/Sent/dovecot.index: Append
> with UID 2431, but next_uid = 2432
> Nov 27 07:35:42 aldebaran dovecot[706]: imap(jakob): Warning: fscking
> index file /var/lib/dovecot/db/indexes/Maildir/jakob/Sent/dovecot.index
> Nov 27 07:35:42 aldebaran dovecot[706]: imap(jakob): save:
> box=INBOX/Sent, uid=2431,
> msgid=<[hidden email]>, size=2693

This is caused by the following

> And then i got this many times:
>
> Nov 27 07:28:43 aldebaran dovecot[706]: imap(jakob): Debug: Mailbox
> INBOX/Sent: Opened mail UID=2427 because: access
> Nov 27 07:28:43 aldebaran dovecot[706]: imap(jakob): Debug: Mailbox
> INBOX/Sent: Opened mail UID=2427 because: mail stream
> Nov 27 07:28:43 aldebaran dovecot[706]: imap(jakob): Panic: file
> mail-index.c: line 679 (mail_index_close): assertion failed:
> (index->open_count > 0)
> Nov 27 07:28:43 aldebaran dovecot[706]: imap(jakob): Error: Raw
> backtrace: /usr/lib/dovecot/libdovecot.so.0(+0x95272) [0x7f4e03f4b272]
> -> /usr/lib/dovecot/libdovecot.so.0(+0x9536d) [0x7f4e03f4b36d] ->
> /usr/lib/dovecot/libdovecot.so.0(i_fatal+0) [0x7f4e03ee1951] ->
> /usr/lib/dovecot/libdovecot-storage.so.0(+0xe0d18) [0x7f4e042b7d18] ->
> /usr/lib/dovecot/libdovecot-storage.so.0(+0xc6f91) [0x7f4e0429df91] ->
> /usr/lib/dovecot/libdovecot-storage.so.0(+0xc7023) [0x7f4e0429e023] ->
> /usr/lib/dovecot/libdovecot-storage.so.0(+0xbe6c4) [0x7f4e042956c4] ->
> /usr/lib/dovecot/libdovecot-storage.so.0(mailbox_free+0x19)
> [0x7f4e042176b9] ->
> /usr/lib/dovecot/modules/lib20_virtual_plugin.so(+0x9dac)
> [0x7f4e028cddac] ->
> /usr/lib/dovecot/modules/lib20_virtual_plugin.so(+0x9e59)
> [0x7f4e028cde59] ->
> /usr/lib/dovecot/libdovecot-storage.so.0(mailbox_close+0x1a)
> [0x7f4e0421762a] ->
> /usr/lib/dovecot/modules/lib20_virtual_plugin.so(+0x9e0c)
> [0x7f4e028cde0c] ->
> /usr/lib/dovecot/modules/lib20_virtual_plugin.so(+0x9e59)
> [0x7f4e028cde59] ->
> /usr/lib/dovecot/libdovecot-storage.so.0(mailbox_close+0x1a)
> [0x7f4e0421762a] ->
> /usr/lib/dovecot/libdovecot-storage.so.0(mailbox_free+0x13)
> [0x7f4e042176b3] -> dovecot/imap [jakob 192.168.0.14 select](+0x142eb)
> [0x560e9dc052eb] -> dovecot/imap [jakob 192.168.0.14
> select](cmd_select_full+0x129) [0x560e9dc05449] -> dovecot/imap [jakob
> 192.168.0.14 select](command_exec+0xa5) [0x560e9dc0c735] -> dovecot/imap
> [jakob 192.168.0.14 select](+0x199c2) [0x560e9dc0a9c2] -> dovecot/imap
> [jakob 192.168.0.14 select](+0x19a4c) [0x560e9dc0aa4c] -> dovecot/imap
> [jakob 192.168.0.14 select](client_handle_input+0x1b5) [0x560e9dc0ae55]
> -> dovecot/imap [jakob 192.168.0.14 select](client_input+0x86)
> [0x560e9dc0b3c6] ->
> /usr/lib/dovecot/libdovecot.so.0(io_loop_call_io+0x52) [0x7f4e03f5fdd2]
> -> /usr/lib/dovecot/libdovecot.so.0(io_loop_handler_run_internal+0x109)
> [0x7f4e03f61409] ->
> /usr/lib/dovecot/libdovecot.so.0(io_loop_handler_run+0x3c)
> [0x7f4e03f5fe6c] -> /usr/lib/dovecot/libdovecot.so.0(io_loop_run+0x38)
> [0x7f4e03f60018] ->
> /usr/lib/dovecot/libdovecot.so.0(master_service_run+0x13)
> [0x7f4e03ee7e93] -> dovecot/imap [jakob 192.168.0.14 select](main+0x328)
> [0x560e9dbfde68]
> Nov 27 07:28:43 aldebaran dovecot[706]: imap(jakob): Fatal: master:
> service(imap): child 6631 killed with signal 6 (core dumps disabled)
>
> What is this?
>
> jakob
>
There has been significant amount of fixes to virtual plugin after
2.2.27. It's possible it's fixed, I was not able to directly confirm
this, would need to have your virtual folder configuration to try.

Aki

Reply | Threaded
Open this post in threaded view
|

Re: lmtp-Eroor

Jakob Schürz
Am 2017-11-27 um 07:45 schrieb Aki Tuomi:

>
>
> On 27.11.2017 08:37, Jakob Schürz wrote:
>> Hi!
>>
>>
>> Am 2017-11-27 um 01:35 schrieb Stephan Bosch:
>>> Op 11/27/2017 om 12:53 AM schreef Jakob Schürz:
>>>> Hi There!
>>>>
>>>> I can not find anything about this message:
>>>>
>>>> dovecot[23202]: lmtp(jakob): Debug: Mailbox <lmtp DATA>: Opened mail
>>>> UID=1 because: header Message-ID (Cache file is unusable)
>>>> dovecot[23202]: lmtp(jakob): Debug: Mailbox <lmtp DATA>: Opened mail
>>>> UID=1 because: virtual size (Cache file is unusable)
>>> These are debug messages. The incoming LMTP DATA stream is a raw
>>> message, which has no cache (hence 'Cache file is unusable'). This is
>>> normal.
>> Ok. I did a lot of debugging the last weeks for another bug. But I've
>> never seen this message before. So i thought it is new. But obviously I
>> haven't seen this, but it was there.
>
> It just means that the cache file wasn't available, because it had been
> removed. It's not a serious problem.
Ok Thank you.

> There has been significant amount of fixes to virtual plugin after
> 2.2.27. It's possible it's fixed, I was not able to directly confirm
> this, would need to have your virtual folder configuration to try.

INBOX/Sent is not a virtual folder. But i have several virtual folders.

Do you need the dovecot-virtual files from my folders?

/var/mail/virtual: # tree
.
├── AKTUELL
│   └── dovecot-virtual
├── Archives
│   └── dovecot-virtual
├── dovecot-acl-list
├── Drafts
│   └── dovecot-virtual
├── GINBOX
│   └── dovecot-virtual
├── Markierte
│   └── dovecot-virtual
├── Neue
│   └── dovecot-virtual
├── Sent
│   └── dovecot-virtual
├── Trash
│   └── dovecot-virtual
└── Ungelesene
    └── dovecot-virtual

cat ./GINBOX/dovecot-virtual
!Real/Alle
 or all younger 31536000 inthread refs x-mailbox INBOX
INBOX/*
 flagged

cat ./Neue/dovecot-virtual
Real/Alle
 recent unseen

cat ./Markierte/dovecot-virtual
Real/Alle
 flagged

cat ./Ungelesene/dovecot-virtual
Real/Alle
 unseen

cat ./Sent/dovecot-virtual
!INBOX/Sent
INBOX/Sent$*
 all

cat ./Drafts/dovecot-virtual
!INBOX/Drafts
INBOX/Drafts$*
 all

cat ./Trash/dovecot-virtual
!INBOX/Trash
INBOX/Trash$*
 all

cat ./AKTUELL/dovecot-virtual
Real/Alle
 inthread refs younger 2592000

cat ./Archives/dovecot-virtual
!INBOX/Archives
INBOX/Archives$*
 all

/var/mail/real: # tree
.
├── Alle
│   └── dovecot-virtual
└── dovecot-acl-list
cat ./Alle/dovecot-virtual
!INBOX
INBOX/*
-INBOX/Trash
-INBOX/Trash$*
-INBOX/Archives
-INBOX/Archives$*
-INBOX/Drafts
-INBOX/Drafts$*
-INBOX/Junk
-INBOX/Junk$*
-INBOX/SYSTEM
-INBOX/SYSTEM$*
 all


The definition of this two namespaces is in my first mail.

Do you need more?

Jakob





signature.asc (915 bytes) Download Attachment
Reply | Threaded
Open this post in threaded view
|

Re: lmtp-Eroor

Aki Tuomi-2


On 27.11.2017 09:29, Jakob Schürz wrote:

> Am 2017-11-27 um 07:45 schrieb Aki Tuomi:
>>
>> On 27.11.2017 08:37, Jakob Schürz wrote:
>>> Hi!
>>>
>>>
>>> Am 2017-11-27 um 01:35 schrieb Stephan Bosch:
>>>> Op 11/27/2017 om 12:53 AM schreef Jakob Schürz:
>>>>> Hi There!
>>>>>
>>>>> I can not find anything about this message:
>>>>>
>>>>> dovecot[23202]: lmtp(jakob): Debug: Mailbox <lmtp DATA>: Opened mail
>>>>> UID=1 because: header Message-ID (Cache file is unusable)
>>>>> dovecot[23202]: lmtp(jakob): Debug: Mailbox <lmtp DATA>: Opened mail
>>>>> UID=1 because: virtual size (Cache file is unusable)
>>>> These are debug messages. The incoming LMTP DATA stream is a raw
>>>> message, which has no cache (hence 'Cache file is unusable'). This is
>>>> normal.
>>> Ok. I did a lot of debugging the last weeks for another bug. But I've
>>> never seen this message before. So i thought it is new. But obviously I
>>> haven't seen this, but it was there.
>> It just means that the cache file wasn't available, because it had been
>> removed. It's not a serious problem.
> Ok Thank you.
>
>> There has been significant amount of fixes to virtual plugin after
>> 2.2.27. It's possible it's fixed, I was not able to directly confirm
>> this, would need to have your virtual folder configuration to try.
> INBOX/Sent is not a virtual folder. But i have several virtual folders.
>
> Do you need the dovecot-virtual files from my folders?
>
> /var/mail/virtual: # tree
> .
> ├── AKTUELL
> │   └── dovecot-virtual
> ├── Archives
> │   └── dovecot-virtual
> ├── dovecot-acl-list
> ├── Drafts
> │   └── dovecot-virtual
> ├── GINBOX
> │   └── dovecot-virtual
> ├── Markierte
> │   └── dovecot-virtual
> ├── Neue
> │   └── dovecot-virtual
> ├── Sent
> │   └── dovecot-virtual
> ├── Trash
> │   └── dovecot-virtual
> └── Ungelesene
>     └── dovecot-virtual
>
> cat ./GINBOX/dovecot-virtual
> !Real/Alle
>  or all younger 31536000 inthread refs x-mailbox INBOX
> INBOX/*
>  flagged
>
> cat ./Neue/dovecot-virtual
> Real/Alle
>  recent unseen
>
> cat ./Markierte/dovecot-virtual
> Real/Alle
>  flagged
>
> cat ./Ungelesene/dovecot-virtual
> Real/Alle
>  unseen
>
> cat ./Sent/dovecot-virtual
> !INBOX/Sent
> INBOX/Sent$*
>  all
>
> cat ./Drafts/dovecot-virtual
> !INBOX/Drafts
> INBOX/Drafts$*
>  all
>
> cat ./Trash/dovecot-virtual
> !INBOX/Trash
> INBOX/Trash$*
>  all
>
> cat ./AKTUELL/dovecot-virtual
> Real/Alle
>  inthread refs younger 2592000
>
> cat ./Archives/dovecot-virtual
> !INBOX/Archives
> INBOX/Archives$*
>  all
>
> /var/mail/real: # tree
> .
> ├── Alle
> │   └── dovecot-virtual
> └── dovecot-acl-list
> cat ./Alle/dovecot-virtual
> !INBOX
> INBOX/*
> -INBOX/Trash
> -INBOX/Trash$*
> -INBOX/Archives
> -INBOX/Archives$*
> -INBOX/Drafts
> -INBOX/Drafts$*
> -INBOX/Junk
> -INBOX/Junk$*
> -INBOX/SYSTEM
> -INBOX/SYSTEM$*
>  all
>
>
> The definition of this two namespaces is in my first mail.
>
> Do you need more?
>
> Jakob
>
>
>
>

The problem, however, seems to affect some virtual folder. Do you have
idea how to reproduce this issue? Can you try causing it?

Aki

Reply | Threaded
Open this post in threaded view
|

Re: lmtp-Eroor

Jakob Schürz
So, i patched the debian-package from sid 2.2.33-2 with the
qresync-patch from last week.

The error on sending emails from thunderbird isn't gone.

Nov 27 12:28:04 aldebaran exim[24198]: 2017-11-27 12:28:04
1eJHa0-0006II-95 <= [hidden email]
H=xxx-xxx-xxx-xxx.xxxx.xxxxxxx.xx ([xxx.xxx.xxx.xxx]) [xxx.xxx.xxx.xxx]
P=esmtpsa X=TLS1.2:ECDHE_RSA_AES_128_GCM_SHA256:128 CV=no
A=dovecot_cram:jakob S=1140 id=[hidden email]
Nov 27 12:28:04 aldebaran exim[24200]: 2017-11-27 12:28:04
1eJHa0-0006II-95 => /var/mail/mailarchiv/jakob/outgoing/
([hidden email]) <[hidden email]> R=archive_sent
T=local_copy_outgoing
Nov 27 12:28:04 aldebaran spamd[23634]: prefork: child states: II
Nov 27 12:28:04 aldebaran dovecot[23927]: imap(jakob): Debug: Mailbox
INBOX/Sent: Opened mail UID=0 because: mail stream
Nov 27 12:28:04 aldebaran dovecot[23927]: imap(jakob): Error: Log
synchronization error at seq=3,offset=184 for
/var/lib/dovecot/db/indexes/Maildir/jakob/Sent/dovecot.index: Append
with UID 1257, but next_uid = 1258
Nov 27 12:28:04 aldebaran dovecot[23927]: imap(jakob): Warning: fscking
index file /var/lib/dovecot/db/indexes/Maildir/jakob/Sent/dovecot.index
Nov 27 12:28:04 aldebaran dovecot[23927]: imap(jakob): save:
box=INBOX/Sent, uid=1257,
msgid=<[hidden email]>, size=405
Nov 27 12:28:05 aldebaran exim[24200]: 2017-11-27 12:28:05
1eJHa0-0006II-95 => [hidden email] R=smarthost_multiaccount
T=remote_smtp_smarthost_multiaccount H=mail.gmx.net [212.227.17.190]
X=TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256 CV=yes DN="C=DE,O=1&1 Mail &
Media
GmbH,ST=Rhineland-Palatinate,L=Montabaur,EMAIL=[hidden email],CN=mail.gmx.net"
A=plain C="250 Requested mail action okay, completed:
id=0Mda8q-1eVIeu3xts-00PP4J"
Nov 27 12:28:05 aldebaran exim[24200]: 2017-11-27 12:28:05
1eJHa0-0006II-95 Completed
Nov 27 12:28:05 aldebaran dovecot[23927]: imap(jakob): Error: Log
synchronization error at seq=3,offset=184 for
/var/lib/dovecot/db/indexes/Maildir/jakob/Sent/dovecot.index: Append
with UID 1257, but next_uid = 1258
Nov 27 12:28:05 aldebaran dovecot[23927]: imap(jakob): Warning: fscking
index file /var/lib/dovecot/db/indexes/Maildir/jakob/Sent/dovecot.index
N