On Apr 2, 2018 9:07 AM, Reio Remma <[email protected]> wrote: > > I don't think it's that, because the messages are small. I've the limit > set at an ample 100M. > > It's almost as if Amavisd is trying to send the mail back twice (maybe, > for each recipient)? > That's what I thought as well, but not sure. It may be attempting pipelining. But I would think the error message would be different.
> Reio > > On 02.04.18 17:04, [email protected] wrote: > > Best guess would be to look at bumping up: > > > > max-message-size > > > > Seems odd that it would make it through to amavis without hitting the limit > > first. > > On Apr 2, 2018 8:08 AM, Reio Remma <[email protected]> wrote: > >> Hello all! > >> > >> Finally managed to upgrade our server from QMail to OpenSMTPD/Dovecot. > >> After ironing out a few small kinks I'm still left with a problem. > >> > >> Our mail comes in and is relayed to Amavisd port 10024, then back to > >> OpenSMTPD port 10025. > >> > >> Everything works except when a mail comes in with multiple recipients - > >> below are logs. > >> > >> OpenSMTPD gives an error ("Remote host sent too much data") on the 2nd > >> recipient when the mail is coming back from Amavisd. > >> > >> Both the recipients' mails are delivered, however the 2nd recipient's > >> mail is stuck in the queue on both sending and receiving servers. > >> > >> In a couple of minutes they both work the queue and the 2nd recipient > >> ends up with 3 messages total. > >> > >> Is there a setting I should change in either OpenSMTPD or Amavisd to > >> avoid this? > >> > >> Thanks! > >> Reio > >> > >> Apr 2 15:27:35 host smtpd[3363]: a1ee25494b874d98 smtp event=connected > >> address=192.168.1.4 host=sender.example.org > >> Apr 2 15:27:35 host smtpd[3363]: a1ee25494b874d98 smtp event=starttls > >> address=192.168.1.4 host=sender.example.org ciphers="version=TLSv1.2, > >> cipher=ECDHE-RSA-AES256-GCM-SHA384, bits=256" > >> Apr 2 15:27:35 host smtpd[3363]: a1ee25494b874d98 smtp event=message > >> address=192.168.1.4 host=sender.example.org msgid=989362f2 > >> from=<[email protected]> to=<[email protected]> size=1911 ndest=1 > >> proto=ESMTP > >> Apr 2 15:27:35 host smtpd[3363]: a1ee25494b874d98 smtp event=message > >> address=192.168.1.4 host=sender.example.org msgid=989362f2 > >> from=<[email protected]> to=<[email protected]> size=1911 ndest=1 > >> proto=ESMTP > >> Apr 2 15:27:35 host smtpd[3363]: a1ee254c29485cd9 mta event=connecting > >> address=lmtp://127.0.0.1:10024 host=localhost > >> Apr 2 15:27:35 host smtpd[3363]: a1ee254c29485cd9 mta event=connected > >> Apr 2 15:27:36 host smtpd[3363]: a1ee253d696ec8c5 smtp event=message > >> address=127.0.0.1 host=localhost msgid=ff083fd6 from=<[email protected]> > >> to=<[email protected]> size=2349 ndest=1 proto=ESMTP > >> Apr 2 15:27:36 host smtpd[3363]: a1ee253d696ec8c5 smtp event=message > >> address=127.0.0.1 host=localhost msgid=ff083fd6 from=<[email protected]> > >> to=<[email protected]> size=2349 ndest=1 proto=ESMTP > >> Apr 2 15:27:36 host dovecot: lmtp(4131): Connect from local > >> Apr 2 15:27:36 host amavis[3767]: (03767-08) Passed CLEAN > >> {RelayedInbound}, [127.0.0.1] <[email protected]> -> > >> <[email protected]>,<[email protected]>, Message-ID: > >> <[email protected]>, mail_id: > >> nLJzF6CeDChQ, Hits: -3.179, size: 1947, queued_as: 250 2.0.0: ff083fd6 > >> Message accepted for delivery, 702 ms > >> Apr 2 15:27:36 host dovecot: lmtp(4136): Connect from local > >> Apr 2 15:27:36 host smtpd[3363]: a1ee254c29485cd9 mta event=delivery > >> evpid=989362f2561abf5b from=<[email protected]> > >> to=<[email protected]> rcpt=<-> source="127.0.0.1" relay="127.0.0.1 > >> (localhost)" delay=1s result="Ok" stat="250 2.0.0 from > >> MTA(smtp:[127.0.0.1]:10025): 250 2.0.0: ff083fd6 Message accepted for > >> delivery" > >> Apr 2 15:27:36 host smtpd[3363]: smtp-out: Error on session > >> a1ee254c29485cd9 after 1 message sent: Remote host sent too much data > >> Apr 2 15:27:36 host smtpd[3363]: a1ee254c29485cd9 mta event=delivery > >> evpid=989362f25851fa3e from=<[email protected]> > >> to=<[email protected]> rcpt=<-> source="127.0.0.1" relay="127.0.0.1 > >> (localhost)" delay=1s result="TempFail" stat="Remote host sent too much > >> data" > >> Apr 2 15:27:36 host smtpd[3363]: a1ee253d696ec8c5 smtp event=closed > >> address=127.0.0.1 host=localhost reason=quit > >> Apr 2 15:27:36 host dovecot: lmtp([email protected]): > >> msgid=<[email protected]>: saved mail to > >> INBOX > >> Apr 2 15:27:36 host dovecot: lmtp(4131): Disconnect from local: Client > >> has quit the connection (state = READY) > >> Apr 2 15:27:36 host smtpd[3363]: 0000000000000000 mda event=delivery > >> evpid=ff083fd626620393 from=<[email protected]> > >> to=<[email protected]> user=recipient2_example.org method=lmtp > >> delay=0s result=Ok stat=Delivered > >> Apr 2 15:27:36 host dovecot: lmtp([email protected]): > >> msgid=<[email protected]>: saved mail to > >> INBOX > >> Apr 2 15:27:36 host dovecot: lmtp(4136): Disconnect from local: Client > >> has quit the connection (state = READY) > >> Apr 2 15:27:36 host smtpd[3363]: 0000000000000000 mda event=delivery > >> evpid=ff083fd6e2c78bfd from=<[email protected]> > >> to=<[email protected]> user=recipient1_example.org method=lmtp > >> delay=0s result=Ok stat=Delivered > >> Apr 2 15:27:45 host smtpd[3363]: a1ee25494b874d98 smtp event=closed > >> address=192.168.1.4 host=sender.example.org reason=quit > >> > >> > >> Sender: > >> b7cd2df5d9ca94ec|inet4|mta|auth|[email protected]|[email protected]|[email protected]|1522673542|1523019142|0|1|pending|353|Remote > >> host sent too much data > >> Recipient: > >> 754dc784cae717d2|inet4|mta||[email protected]|[email protected]|[email protected]|1522673543|1523019143|0|1|pending|340|Remote > >> host sent too much data > >> > >> > >> Apr 2 15:34:14 host smtpd[3363]: a1ee256e785cc841 smtp event=connected > >> address=192.168.1.4 host=sender.example.org > >> Apr 2 15:34:14 host smtpd[3363]: a1ee256e785cc841 smtp event=starttls > >> address=192.168.1.4 host=sender.example.org ciphers="version=TLSv1.2, > >> cipher=ECDHE-RSA-AES256-GCM-SHA384, bits=256" > >> Apr 2 15:34:14 host smtpd[3363]: a1ee256e785cc841 smtp event=message > >> address=192.168.1.4 host=sender.example.org msgid=e245a476 > >> from=<[email protected]> to=<[email protected]> size=2004 ndest=1 > >> proto=ESMTP > >> Apr 2 15:34:14 host smtpd[3363]: a1ee2571543ee07d mta event=connecting > >> address=lmtp://127.0.0.1:10024 host=localhost > >> Apr 2 15:34:14 host smtpd[3363]: a1ee2571543ee07d mta event=connected > >> Apr 2 15:34:15 host smtpd[3363]: a1ee2572eadf1ee6 smtp event=connected > >> address=127.0.0.1 host=localhost > >> Apr 2 15:34:15 host smtpd[3363]: a1ee2572eadf1ee6 smtp event=message > >> address=127.0.0.1 host=localhost msgid=9d4bd574 from=<[email protected]> > >> to=<[email protected]> size=2504 ndest=1 proto=ESMTP > >> Apr 2 15:34:15 host amavis[3624]: (03624-16) Passed CLEAN > >> {RelayedInbound}, [127.0.0.1] <[email protected]> -> > >> <[email protected]>, Message-ID: > >> <[email protected]>, mail_id: > >> V6N9QkDUiUdB, Hits: -2.926, size: 2043, queued_as: 250 2.0.0: 9d4bd574 > >> Message accepted for delivery, 580 ms > >> Apr 2 15:34:15 host smtpd[3363]: a1ee2571543ee07d mta event=delivery > >> evpid=e245a4768eb97ec1 from=<[email protected]> > >> to=<[email protected]> rcpt=<-> source="127.0.0.1" relay="127.0.0.1 > >> (localhost)" delay=1s result="Ok" stat="250 2.0.0 from > >> MTA(smtp:[127.0.0.1]:10025): 250 2.0.0: 9d4bd574 Message accepted for > >> delivery" > >> Apr 2 15:34:15 host dovecot: lmtp(4166): Connect from local > >> Apr 2 15:34:15 host dovecot: lmtp([email protected]): > >> msgid=<[email protected]>: saved mail to > >> INBOX > >> Apr 2 15:34:15 host dovecot: lmtp(4166): Disconnect from local: Client > >> has quit the connection (state = READY) > >> Apr 2 15:34:15 host smtpd[3363]: 0000000000000000 mda event=delivery > >> evpid=9d4bd574d7f14c2f from=<[email protected]> > >> to=<[email protected]> user=recipient2_example.org method=lmtp > >> delay=0s result=Ok stat=Delivered > >> Apr 2 15:34:15 host smtpd[3363]: a1ee2572eadf1ee6 smtp event=message > >> address=127.0.0.1 host=localhost msgid=6d82ebcd from=<[email protected]> > >> to=<[email protected]> size=2399 ndest=1 proto=ESMTP > >> Apr 2 15:34:15 host dovecot: lmtp(4166): Connect from local > >> Apr 2 15:34:16 host amavis[3624]: (03624-16-2) Passed CLEAN > >> {RelayedInbound}, [127.0.0.1] <[email protected]> -> > >> <[email protected]>, Message-ID: > >> <[email protected]>, mail_id: > >> GYBRJw-WsU6T, Hits: -2.926, size: 1947, queued_as: 250 2.0.0: 6d82ebcd > >> Message accepted for delivery, 554 ms > >> Apr 2 15:34:16 host smtpd[3363]: a1ee2571543ee07d mta event=delivery > >> evpid=989362f25851fa3e from=<[email protected]> > >> to=<[email protected]> rcpt=<-> source="127.0.0.1" relay="127.0.0.1 > >> (localhost)" delay=6m41s result="Ok" stat="250 2.0.0 from > >> MTA(smtp:[127.0.0.1]:10025): 250 2.0.0: 6d82ebcd Message accepted for > >> delivery" > >> Apr 2 15:34:16 host dovecot: lmtp([email protected]): > >> msgid=<[email protected]>: saved mail to > >> INBOX > >> Apr 2 15:34:16 host dovecot: lmtp(4166): Disconnect from local: Client > >> has quit the connection (state = READY) > >> Apr 2 15:34:16 host smtpd[3363]: 0000000000000000 mda event=delivery > >> evpid=6d82ebcd60244358 from=<[email protected]> > >> to=<[email protected]> user=recipient1_example.org method=lmtp > >> delay=1s result=Ok stat=Delivered > > > -- > You received this mail because you are subscribed to [email protected] > To unsubscribe, send a mail to: [email protected] >
