New Installation Help

[Will]

On 3/25/2024 3:05 PM, Mark Sapiro wrote:
> On 3/25/24 11:52, Will wrote:
>>
>> On 3/25/2024 2:41 PM, Mark Sapiro wrote:
>>> On 3/25/24 10:52, Will wrote:
>>>> Now my 2nd issue is that the milter wants to reject my test emails. 
>>>> Is there a way for me to tell why?
>>>>
>>>> Mar 25 13:42:44 dcsnow postfix/smtpd[51681]: connect from 
>>>> mail-il1-f176.google.com[209.85.166.176]
>>>> Mar 25 13:42:44 dcsnow postfix/smtpd[51681]: B128610A0C41: 
>>>> client=mail-il1-f176.google.com[209.85.166.176]
>>>> Mar 25 13:42:44 dcsnow postfix/cleanup[51687]: B128610A0C41: 
>>>> message-id=<CADk0RN5j+yHk6377WsRuMoKSyZi-oz9KBTc8tCk7SrMw+z6Mrw at mail.gmail.com> 
>>>>
>>>> Mar 25 13:42:45 dcsnow postfix/cleanup[51687]: B128610A0C41: 
>>>> milter-discard: END-OF-MESSAGE from 
>>>> mail-il1-f176.google.com[209.85.166.176]: milter triggers DISCARD 
>>>> action; from=<wyonker at gmail.com> to=<wyonker at dcsnow.com> 
>>>> proto=ESMTP helo=<mail-il1-f176.google.com>
> ...
>> Okay but it is actually rejecting the mail and sending reject 
>> messages to the senders!  Did I not include the correct bits in the log?
>
> What you included above is all normal for every message. Following 
> those you should see
>
> ... MailScanner[<pid>]: New Batch: Scanning 1 messages, ...
>
> followed by more `MailScanner[<pid>]:` messages. What are these?
>
> There are reasons why the milter itself will reject a message. but 
> then you will see `milter triggers REJECT` and not `milter triggers 
> DISCARD`.
>
Double sorry because my first reply wasn't to the list.  Sorry again, I 
thought that was the important part.  Here is another attempt where I 
think I captured everything.  The only thing I see is that postfix is 
setting the sender to <>.  But isn't that just a warning?  I don't see 
the same warning if the milter is disabled though.

Mar 26 10:52:54 dcsnow postfix/smtpd[9946]: connect from 
mail-io1-f45.google.com[209.85.166.45]
Mar 26 10:52:54 dcsnow postfix/smtpd[9946]: D78F410A0C41: 
client=mail-io1-f45.google.com[209.85.166.45]
Mar 26 10:52:54 dcsnow postfix/cleanup[9952]: D78F410A0C41: 
message-id=<CADk0RN46UssD4xrcHQ8fKXZiUfwbhbfe_vN5QP9orx9OHkamuA at mail.gmail.com> 

Mar 26 10:52:55 dcsnow postfix/cleanup[9952]: D78F410A0C41: 
milter-discard: END-OF-MESSAGE from 
mail-io1-f45.google.com[209.85.166.45]: milter triggers DISCARD action; 
from=<wyonker at gmail.com> to=<wyonker at dcsnow.com> proto=ESMTP 
helo=<mail-io1-f45.google.com>
Mar 26 10:52:55 dcsnow postfix/smtpd[9946]: disconnect from 
mail-io1-f45.google.com[209.85.166.45] ehlo=2 starttls=1 mail=1 rcpt=1 
bdat=1 quit=1 commands=7
Mar 26 10:52:55 dcsnow MailScanner[8667]: New Batch: Scanning 1 
messages, 2941 bytes
Mar 26 10:52:55 dcsnow MailScanner[8667]: Reject message 
D78F410A0C41.ACA73 from wyonker at gmail.com with report 
/usr/share/MailScanner/reports/en/rejection.report.txt
Mar 26 10:52:55 dcsnow sendmail[9953]: 42QEqtJl009953: 
Authentication-Warning: dcsnow.com: postfix set sender to <> using -f
Mar 26 10:52:55 dcsnow sendmail[9953]: 42QEqtJl009953: from=<>, 
size=555, class=0, nrcpts=1, 
msgid=<202403261452.42QEqtJl009953 at dcsnow.com>, relay=postfix at localhost
Mar 26 10:52:55 dcsnow postfix/smtpd[9946]: connect from 
localhost[127.0.0.1]
Mar 26 10:52:56 dcsnow sendmail[9953]: STARTTLS=client, 
relay=[127.0.0.1], version=TLSv1.3, verify=FAIL, 
cipher=TLS_AES_256_GCM_SHA384, bits=256/256
Mar 26 10:52:56 dcsnow postfix/smtpd[9946]: 28B4010A0C41: 
client=localhost[127.0.0.1]
Mar 26 10:52:56 dcsnow postfix/cleanup[9952]: 28B4010A0C41: 
message-id=<202403261452.42QEqtJl009953 at dcsnow.com>
Mar 26 10:52:56 dcsnow postfix/cleanup[9952]: 28B4010A0C41: 
milter-discard: END-OF-MESSAGE from localhost[127.0.0.1]: milter 
triggers DISCARD action; from=<> to=<wyonker at gmail.com> proto=ESMTP 
helo=<dcsnow.com>
Mar 26 10:52:56 dcsnow sendmail[9953]: 42QEqtJl009953: 
to=wyonker at gmail.com, delay=00:00:01, xdelay=00:00:01, mailer=relay, 
pri=30555, relay=[127.0.0.1] [127.0.0.1], dsn=2.0.0, stat=Sent (Ok: 
queued as 28B4010A0C41)
Mar 26 10:52:56 dcsnow postfix/smtpd[9946]: disconnect from 
localhost[127.0.0.1] ehlo=2 starttls=1 mail=1 rcpt=1 data=1 quit=1 
commands=7
Mar 26 10:52:56 dcsnow MailScanner[8667]: Deleted 1 messages from 
processing-database
Mar 26 10:52:56 dcsnow MailScanner[8667]: New Batch: Scanning 1 
messages, 1160 bytes
Mar 26 10:52:56 dcsnow MailScanner[8667]: Virus and Content Scanning: 
Starting
Mar 26 10:52:58 dcsnow MailScanner[8667]: Requeue: 28B4010A0C41.A804C to 
28B4010A0C41.A804C
Mar 26 10:52:58 dcsnow postfix/qmqpd[9965]: connect from 
localhost[127.0.0.1]
Mar 26 10:52:58 dcsnow postfix/qmqpd[9965]: 473EF10A0C41: 
client=localhost[127.0.0.1]
Mar 26 10:52:58 dcsnow postfix/cleanup[9952]: 473EF10A0C41: 
message-id=<202403261452.42QEqtJl009953 at dcsnow.com>
Mar 26 10:52:58 dcsnow postfix/qmqpd[9965]: disconnect from 
localhost[127.0.0.1]
Mar 26 10:52:58 dcsnow postfix/qmgr[9943]: 473EF10A0C41: from=<>, 
size=1545, nrcpt=1 (queue active)
Mar 26 10:52:58 dcsnow MailScanner[8667]: Uninfected: Delivered 1 messages
Mar 26 10:52:58 dcsnow MailScanner[8667]: Deleted 1 messages from 
processing-database
Mar 26 10:53:00 dcsnow postfix/smtp[9966]: 473EF10A0C41: 
to=<wyonker at gmail.com>, relay=outbound.mailhop.org[54.200.129.228]:587, 
delay=2.7, delays=0/0.05/1.2/1.4, dsn=2.0.0, status=sent (250 2.0.0 Ok: 
queued as 9b55e80d-eae3-11ee-8e5a-2b2b5a144ce7)

Mar 26 10:53:00 dcsnow postfix/qmgr[9943]: 473EF10A0C41: removed