Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Log files are overflowing with milter messages #181

Closed
dirkhh opened this issue Feb 14, 2024 · 1 comment
Closed

Log files are overflowing with milter messages #181

dirkhh opened this issue Feb 14, 2024 · 1 comment

Comments

@dirkhh
Copy link

dirkhh commented Feb 14, 2024

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
Copy link
Author

dirkhh commented Feb 15, 2024

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

@dirkhh dirkhh closed this as completed Feb 15, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
1 participant
@dirkhh