Menu
▾
▴
[Assp-user] Multiple deliveries
|
From: Radek Č. <ass...@st...> - 2018-10-02 14:27:34
|
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...@st... |
