Hi everyone,
I'm having a problem with e-mails with larger attachments (above 4-5 MB)
being delivered multiple times as they are resent by the senders MTA again
and again.

After some testing I've found that setting longer timeout in sending mail
client solves the issue, but it's not an option as every client connecting
directly to my server would have to change the timeout and of course
including all sending MTAs.

I've also tested connecting directly to hMailserver on alternate SMTP port
and delivery took just a second or two (attachment of 15 MB size). When
connecting to ASSP, it takes from half a minute to minutes. Even when the
delivery takes shorter time than is the client timeout, it gets resent
again.

Running ASSP 2.6.1 18128 -> hMailserver 5.6.8 2431 -> ClamAV (max. 2 MB size
limit set in hMail).

Excerpt from the ASSP log:
-----------------------------------
Oct-01-18 23:26:06 m1-29166-13205 [Worker_1] IP.IP.IP.IP
<sender@his_domain.com> info: found message size announcement: 12.80 MByte
Oct-01-18 23:26:06 m1-29166-13205 [Worker_1] IP.IP.IP.IP
<sender@his_domain.com> message proxied without processing - message size
(13426213) is above 500000 (npSize).
Oct-01-18 23:26:06 m1-29166-13205 [Worker_1] IP.IP.IP.IP
<sender@his_domain.com> [SMTP Reply] 250 OK
Oct-01-18 23:26:06 m1-29166-13205 [Worker_1] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com [SMTP Reply] 250 OK
Oct-01-18 23:26:06 m1-29166-13205 [Worker_1] [NoProcessing] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com message proxied without
processing content base check (npSize)
Oct-01-18 23:26:06 m1-29166-13205 [Worker_1] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com [SMTP Reply] 354 OK,
send.
Oct-01-18 23:26:07 m1-29166-13205 [Worker_1] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com Whitelisted sender
address: sender@his_domain.com for recipient recipient@my_domain.com
Oct-01-18 23:26:07 m1-29166-13205 [Worker_1] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com DKIM-Signature found
Oct-01-18 23:26:07 m1-29166-13205 [Worker_1] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com info: found known good
HELO 'MX_hostname' - weight is -2
Oct-01-18 23:26:07 m1-29166-13205 [Worker_1] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com Message-Score: added -40
for KnownGoodHelo, total score for this message is now -40
Oct-01-18 23:26:07 m1-29166-13205 [Worker_1] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com info: DKIM-signature
precheck is skipped - DKIM result is 'pass'
Oct-01-18 23:26:07 m1-29166-13205 [Worker_1] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com info: domain seznam.cz
has published a DMARC record
Oct-01-18 23:26:48 m1-29166-13205 [Worker_1] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com info: message is too
large ( SIZE 13426213 byte > neverQueueSize 10971520 byte) to be queued for
further internal processing! Skipping DKIM, Plugins and charset conversion.
Oct-01-18 23:26:48 m1-29166-13205 [Worker_1] [MessageOK] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com message ok -
(noProcessing - message size (13426213) is above 500000 (npSize)) -
[Mail_subject]
-----------------------------------

>From hMailserver log for this delivery:
"SMTPD" 3316    1011591 "2018-10-01 23:28:21.409"       "127.0.0.1"
"SENT: 250 Queued (134.400 seconds)"


***** 27 other repeated deliveries here *****

Oct-02-18 13:31:58 m1-79918-00524 [Worker_3] IP.IP.IP.IP
<sender@his_domain.com> info: found message size announcement: 12.80 MByte
Oct-02-18 13:31:58 m1-79918-00524 [Worker_3] IP.IP.IP.IP
<sender@his_domain.com> message proxied without processing - message size
(13426213) is above 500000 (npSize).
Oct-02-18 13:31:58 m1-79918-00524 [Worker_3] IP.IP.IP.IP
<sender@his_domain.com> [SMTP Reply] 250 OK
Oct-02-18 13:31:58 m1-79918-00524 [Worker_3] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com [SMTP Reply] 250 OK
Oct-02-18 13:31:58 m1-79918-00524 [Worker_3] [NoProcessing] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com message proxied without
processing content base check (npSize)
Oct-02-18 13:31:58 m1-79918-00524 [Worker_3] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com [SMTP Reply] 354 OK,
send.
Oct-02-18 13:31:58 m1-79918-00524 [Worker_3] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com Whitelisted sender
address: sender@his_domain.com for recipient recipient@my_domain.com
Oct-02-18 13:31:58 m1-79918-00524 [Worker_3] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com DKIM-Signature found
Oct-02-18 13:31:58 m1-79918-00524 [Worker_3] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com info: found known good
HELO 'MX_hostname' - weight is -2
Oct-02-18 13:31:58 m1-79918-00524 [Worker_3] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com Message-Score: added -40
for KnownGoodHelo, total score for this message is now -40
Oct-02-18 13:31:58 m1-79918-00524 [Worker_3] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com info: DKIM-signature
precheck is skipped - DKIM result is 'pass'
Oct-02-18 13:31:58 m1-79918-00524 [Worker_3] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com info: domain seznam.cz
has published a DMARC record
Oct-02-18 13:32:06 m1-79918-00524 [Worker_3] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com info: message is too
large ( SIZE 13426213 byte > neverQueueSize 10971520 byte) to be queued for
further internal processing! Skipping DKIM, Plugins and charset conversion.
Oct-02-18 13:32:06 m1-79918-00524 [Worker_3] [MessageOK] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com message ok -
(noProcessing - message size (13426213) is above 500000 (npSize)) -
[Mail_subject]
Oct-02-18 13:32:28 m1-79918-00524 [Worker_3] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com [SMTP Reply] 250 Queued
(29.952 seconds)
Oct-02-18 13:32:28 m1-79918-00524 [Worker_3] IP.IP.IP.IP
<sender@his_domain.com> to: recipient@my_domain.com [SMTP Reply] 221 goodbye

Please notice the last two lines of the log - only the last delivery gets
"[SMTP Reply] 250 Queued (29.952 seconds)", it's the 29th repeat. Even when
hMailserver responded with "250 Queued" during previous deliveries.

Server hardware performance should not be the issue (8-core CPU, 32 GB RAM,
SSD, average CPU-usage 0-2 %) so I suspect some error in my ASSP setup. 

Strange thing is that these e-mails should be treated "no-processing" as I
have set 0,5 MB size limit for that. So it should take even less power to
process than usual e-mails.

Any clue where to look, what to tweak to solve this?

Thank you for any advice!
 
Radek Čejdík
ra...@starlife.cz




_______________________________________________
Assp-user mailing list
Assp-user@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/assp-user

Reply via email to