search

bokysan / docker-postfix

Multi architecture simple SMTP server (postfix null relay) host for your Docker and Kubernetes containers. Based on Debian/Ubuntu/Alpine.
MIT License
555 stars 152 forks source link

Log files are overflowing with milter messages #181

Closed dirkhh closed 9 months ago

dirkhh commented 9 months ago

every 30 seconds I get pretty much the below and I can't figure out what triggers this. I have opendkim enabled...

2024-02-14T14:47:23.698773-08:00 INFO    postfix/smtpd[39551]: smtp_stream_setup: maxtime=300 enable_deadline=0 min_data_rate=0
2024-02-14T14:47:23.698779-08:00 INFO    postfix/smtpd[39551]: match_hostname: smtpd_client_event_limit_exceptions: localhost ~? 172.22.0.0/16
2024-02-14T14:47:23.698783-08:00 INFO    postfix/smtpd[39551]: match_hostaddr: smtpd_client_event_limit_exceptions: 127.0.0.1 ~? 172.22.0.0/16
2024-02-14T14:47:23.698786-08:00 INFO    postfix/smtpd[39551]: match_hostname: smtpd_client_event_limit_exceptions: localhost ~? 127.0.0.0/8
2024-02-14T14:47:23.698788-08:00 INFO    postfix/smtpd[39551]: match_hostaddr: smtpd_client_event_limit_exceptions: 127.0.0.1 ~? 127.0.0.0/8
2024-02-14T14:47:23.698793-08:00 INFO    postfix/smtpd[39551]: report connect to all milters
2024-02-14T14:47:23.698797-08:00 INFO    postfix/smtpd[39551]: milter8_connect: non-protocol events for protocol version 6: 
2024-02-14T14:47:23.698800-08:00 INFO    postfix/smtpd[39551]: milter8_connect: transport=inet endpoint=localhost:8891
2024-02-14T14:47:23.698863-08:00 INFO    postfix/smtpd[39551]: trying... [::1]
2024-02-14T14:47:23.698927-08:00 INFO    postfix/smtpd[39551]: Connection refused
2024-02-14T14:47:23.698930-08:00 INFO    postfix/smtpd[39551]: trying... [127.0.0.1]
2024-02-14T14:47:23.698979-08:00 INFO    postfix/smtpd[39551]: vstream_tweak_tcp: TCP_MAXSEG 32741
2024-02-14T14:47:23.698982-08:00 INFO    postfix/smtpd[39551]: fd=12: stream buffer size old=0 new=130964
2024-02-14T14:47:23.698985-08:00 INFO    postfix/smtpd[39551]: milter8_connect: my_version=0x6
2024-02-14T14:47:23.698993-08:00 INFO    postfix/smtpd[39551]: milter8_connect: my_actions=0x1ff SMFIF_ADDHDRS SMFIF_CHGBODY SMFIF_ADDRCPT SMFIF_DELRCPT SMFIF_CHGHDRS SMFIF_QUARANTINE SMFIF_CHGFROM SMFIF_ADDRCPT_PAR SMFIF_SETSYMLIST
2024-02-14T14:47:23.699001-08:00 INFO    postfix/smtpd[39551]: milter8_connect: my_events=0x1fffff SMFIP_NOCONNECT SMFIP_NOHELO SMFIP_NOMAIL SMFIP_NORCPT SMFIP_NOBODY SMFIP_NOHDRS SMFIP_NOEOH SMFIP_NR_HDR SMFIP_NOUNKNOWN SMFIP_NODATA SMFIP_SKIP SMFIP_RCPT_REJ SMFIP_NR_CONN SMFIP_NR_HELO SMFIP_NR_MAIL SMFIP_NR_RCPT SMFIP_NR_DATA SMFIP_NR_UNKN SMFIP_NR_EOH SMFIP_NR_BODY SMFIP_HDR_LEADSPC
2024-02-14T14:47:23.700186-08:00 INFO    postfix/smtpd[39551]: milter8_connect: milter inet:localhost:8891 version 6
2024-02-14T14:47:23.700194-08:00 INFO    postfix/smtpd[39551]: milter8_connect: events SMFIP_NOHELO SMFIP_NOUNKNOWN SMFIP_NODATA SMFIP_SKIP SMFIP_HDR_LEADSPC
2024-02-14T14:47:23.700198-08:00 INFO    postfix/smtpd[39551]: milter8_connect: requests SMFIF_ADDHDRS SMFIF_CHGHDRS SMFIF_SETSYMLIST
2024-02-14T14:47:23.700744-08:00 INFO    postfix/smtpd[39551]: milter_macro_lookup: "j"
2024-02-14T14:47:23.700751-08:00 INFO    postfix/smtpd[39551]: milter_macro_lookup: result "<hostname>"
2024-02-14T14:47:23.700755-08:00 INFO    postfix/smtpd[39551]: milter_macro_lookup: "{daemon_name}"
2024-02-14T14:47:23.700758-08:00 INFO    postfix/smtpd[39551]: milter_macro_lookup: result "<hostname>"
2024-02-14T14:47:23.700762-08:00 INFO    postfix/smtpd[39551]: milter_macro_lookup: "{daemon_addr}"
2024-02-14T14:47:23.700765-08:00 INFO    postfix/smtpd[39551]: milter_macro_lookup: result "127.0.0.1"
2024-02-14T14:47:23.700768-08:00 INFO    postfix/smtpd[39551]: milter_macro_lookup: "v"
2024-02-14T14:47:23.700770-08:00 INFO    postfix/smtpd[39551]: milter_macro_lookup: result "Postfix 3.7.9"
2024-02-14T14:47:23.700773-08:00 INFO    postfix/smtpd[39551]: milter_macro_lookup: "_"
2024-02-14T14:47:23.700777-08:00 INFO    postfix/smtpd[39551]: milter_macro_lookup: result "localhost [127.0.0.1]"
2024-02-14T14:47:23.701356-08:00 INFO    postfix/smtpd[39551]: milter8_conn_event: milter inet:localhost:8891: connect localhost/127.0.0.1
2024-02-14T14:47:23.701369-08:00 INFO    postfix/smtpd[39551]: event: SMFIC_CONNECT; macros: j=<hostname> {daemon_name}=<hostname> {daemon_addr}=127.0.0.1 v=Postfix 3.7.9 _=localhost [127.0.0.1]
2024-02-14T14:47:23.702370-08:00 INFO    postfix/smtpd[39551]: reply: SMFIR_CONTINUE data 0 bytes
2024-02-14T14:47:23.702632-08:00 INFO    postfix/smtpd[39551]: > localhost[127.0.0.1]: 220 <hostname> ESMTP Postfix (Debian/GNU)
2024-02-14T14:47:23.702644-08:00 INFO    postfix/smtpd[39551]: smtp_stream_setup: maxtime=300 enable_deadline=0 min_data_rate=0
2024-02-14T14:47:23.702648-08:00 INFO    postfix/smtpd[39551]: watchdog_pat: 0xaaaaecad8420
2024-02-14T14:47:24.704995-08:00 INFO    postfix/smtpd[39551]: < localhost[127.0.0.1]: EHLO healthcheck
2024-02-14T14:47:24.705043-08:00 INFO    postfix/smtpd[39551]: report helo to all milters
2024-02-14T14:47:24.705054-08:00 INFO    postfix/smtpd[39551]: milter_macro_lookup: "{tls_version}"
2024-02-14T14:47:24.705062-08:00 INFO    postfix/smtpd[39551]: milter_macro_lookup: "{cipher}"
2024-02-14T14:47:24.705069-08:00 INFO    postfix/smtpd[39551]: milter_macro_lookup: "{cipher_bits}"
2024-02-14T14:47:24.705076-08:00 INFO    postfix/smtpd[39551]: milter_macro_lookup: "{cert_subject}"
2024-02-14T14:47:24.705083-08:00 INFO    postfix/smtpd[39551]: milter_macro_lookup: "{cert_issuer}"
2024-02-14T14:47:24.705092-08:00 INFO    postfix/smtpd[39551]: milter8_helo_event: milter inet:localhost:8891: helo healthcheck
2024-02-14T14:47:24.705100-08:00 INFO    postfix/smtpd[39551]: event: SMFIC_HELO; macros: (none)
2024-02-14T14:47:24.705109-08:00 INFO    postfix/smtpd[39551]: skipping event SMFIC_HELO for milter inet:localhost:8891
2024-02-14T14:47:24.705124-08:00 INFO    postfix/smtpd[39551]: match_list_match: localhost: no match
2024-02-14T14:47:24.705131-08:00 INFO    postfix/smtpd[39551]: match_list_match: 127.0.0.1: no match
2024-02-14T14:47:24.705143-08:00 INFO    postfix/smtpd[39551]: > localhost[127.0.0.1]: 250-<hostname>
2024-02-14T14:47:24.705153-08:00 INFO    postfix/smtpd[39551]: > localhost[127.0.0.1]: 250-PIPELINING
2024-02-14T14:47:24.705160-08:00 INFO    postfix/smtpd[39551]: > localhost[127.0.0.1]: 250-SIZE 40960000
2024-02-14T14:47:24.705168-08:00 INFO    postfix/smtpd[39551]: > localhost[127.0.0.1]: 250-VRFY
2024-02-14T14:47:24.705176-08:00 INFO    postfix/smtpd[39551]: > localhost[127.0.0.1]: 250-ETRN
2024-02-14T14:47:24.705183-08:00 INFO    postfix/smtpd[39551]: > localhost[127.0.0.1]: 250-STARTTLS
2024-02-14T14:47:24.705191-08:00 INFO    postfix/smtpd[39551]: > localhost[127.0.0.1]: 250-ENHANCEDSTATUSCODES
2024-02-14T14:47:24.705199-08:00 INFO    postfix/smtpd[39551]: > localhost[127.0.0.1]: 250-8BITMIME
2024-02-14T14:47:24.705207-08:00 INFO    postfix/smtpd[39551]: > localhost[127.0.0.1]: 250-DSN
2024-02-14T14:47:24.705214-08:00 INFO    postfix/smtpd[39551]: > localhost[127.0.0.1]: 250-SMTPUTF8
2024-02-14T14:47:24.705221-08:00 INFO    postfix/smtpd[39551]: > localhost[127.0.0.1]: 250 CHUNKING
2024-02-14T14:47:24.705232-08:00 INFO    postfix/smtpd[39551]: smtp_stream_setup: maxtime=300 enable_deadline=0 min_data_rate=0
2024-02-14T14:47:24.705249-08:00 INFO    postfix/smtpd[39551]: watchdog_pat: 0xaaaaecad8420
2024-02-14T14:47:25.707492-08:00 INFO    postfix/smtpd[39551]: < localhost[127.0.0.1]: quit
2024-02-14T14:47:25.707520-08:00 INFO    postfix/smtpd[39551]: > localhost[127.0.0.1]: 221 2.0.0 Bye
2024-02-14T14:47:25.707559-08:00 INFO    postfix/smtpd[39551]: match_hostname: smtpd_client_event_limit_exceptions: localhost ~? 172.22.0.0/16
2024-02-14T14:47:25.707567-08:00 INFO    postfix/smtpd[39551]: match_hostaddr: smtpd_client_event_limit_exceptions: 127.0.0.1 ~? 172.22.0.0/16
2024-02-14T14:47:25.707604-08:00 INFO    postfix/smtpd[39551]: match_hostname: smtpd_client_event_limit_exceptions: localhost ~? 127.0.0.0/8
2024-02-14T14:47:25.707610-08:00 INFO    postfix/smtpd[39551]: match_hostaddr: smtpd_client_event_limit_exceptions: 127.0.0.1 ~? 127.0.0.0/8
2024-02-14T14:47:25.707618-08:00 INFO    postfix/smtpd[39551]: abort all milters
2024-02-14T14:47:25.707626-08:00 INFO    postfix/smtpd[39551]: milter8_abort: abort milter inet:localhost:8891
2024-02-14T14:47:25.707639-08:00 INFO    postfix/smtpd[39551]: disconnect event to all milters
2024-02-14T14:47:25.707673-08:00 INFO    postfix/smtpd[39551]: milter8_disc_event: quit milter inet:localhost:8891
2024-02-14T14:47:25.707817-08:00 INFO    postfix/smtpd[39551]: free all milters
2024-02-14T14:47:25.707823-08:00 INFO    postfix/smtpd[39551]: free milter inet:localhost:8891
dirkhh commented 9 months ago

never mind... this was caused by incorrectly resetting the INBOUND_DEBUG