dovecot mailing list stopped delivering mail

Christian Anthon anthon at rth.dk
Thu Mar 28 13:59:28 EET 2019 

Strangest thing. Since some time Marts 25. I'm no longer receiving mails 
from the dovecot mailing list server. Mails from other sources are 
behaving fine.

Instead I'm just seing

Mar 26 08:27:54 dna01 postfix/smtpd[107746]: Anonymous TLS connection 
established from talvi.dovecot.org[94.237.25.159]: TLSv1.2 with cipher 
ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)
Mar 26 08:27:54 dna01 postfix/smtpd[107746]: disconnect from 
talvi.dovecot.org[94.237.25.159] ehlo=1 starttls=1 quit=1 commands=3

And then no more.

I tried turning up the postfix verbosity, but I'm none the wiser. I 
realize that this is probably not a dovecot issue, but anybody with any 
ideas?

Cheers, Christian (and please send an off-list reply also)

Verbose log:

Mar 28 10:57:42 dna01 postfix/smtpd[66648]: connection established
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: master_notify: status 0
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: name_mask: resource
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: name_mask: software
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: connect from 
talvi.dovecot.org[94.237.25.159]
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: match_list_match: 
talvi.dovecot.org: no match
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: match_list_match: 
94.237.25.159: no match
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: match_list_match: 
talvi.dovecot.org: no match
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: match_list_match: 
94.237.25.159: no match
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: smtp_stream_setup: 
maxtime=300 enable_deadline=0
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: match_hostname: 
smtpd_client_event_limit_exceptions: talvi.dovecot.org ~? 192.168.3.0/24
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: match_hostaddr: 
smtpd_client_event_limit_exceptions: 94.237.25.159 ~? 192.168.3.0/24
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: match_hostname: 
smtpd_client_event_limit_exceptions: talvi.dovecot.org ~? 127.0.0.0/8
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: match_hostaddr: 
smtpd_client_event_limit_exceptions: 94.237.25.159 ~? 127.0.0.0/8
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: match_list_match: 
talvi.dovecot.org: no match
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: match_list_match: 
94.237.25.159: no match
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: send attr request = connect
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: send attr ident = 
smtp:94.237.25.159
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: private/anvil: wanted 
attribute: status
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute name: status
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute value: 0
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: private/anvil: wanted 
attribute: count
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute name: count
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute value: 1
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: private/anvil: wanted 
attribute: rate
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute name: rate
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute value: 1
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: private/anvil: wanted 
attribute: (list terminator)
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute name: (end)
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: report connect to all milters
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter_macro_lookup: "j"
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter_macro_lookup: result 
"genome.ku.dk"
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter_macro_lookup: 
"{daemon_name}"
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter_macro_lookup: result 
"genome.ku.dk"
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter_macro_lookup: 
"{daemon_addr}"
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter_macro_lookup: result 
"192.168.3.112"
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter_macro_lookup: "v"
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter_macro_lookup: result 
"Postfix 3.3.3"
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter8_connect: 
non-protocol events for protocol version 6:
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter8_connect: 
transport=inet endpoint=localhost:11332
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: trying... [127.0.0.1]
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: vstream_tweak_tcp: 
TCP_MAXSEG 32741
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: fd=11: stream buffer size 
old=0 new=65482
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter8_connect: my_version=0x6
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter8_connect: 
my_actions=0x1ff SMFIF_ADDHDRS SMFIF_CHGBODY SMFIF_ADDRCPT SMFIF_DELRCPT 
SMFIF_CHGHDRS SMFIF_QUARANTINE SMFIF_CHGFROM SMFIF_ADDRCPT_PAR 
SMFIF_SETSYMLIST
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: 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
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter8_connect: milter 
inet:localhost:11332 version 6
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter8_connect: events 
SMFIP_NR_HDR SMFIP_NR_CONN SMFIP_NR_HELO SMFIP_NR_MAIL SMFIP_NR_RCPT 
SMFIP_NR_DATA SMFIP_NR_UNKN SMFIP_NR_EOH SMFIP_NR_BODY
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter8_connect: requests 
SMFIF_ADDHDRS SMFIF_CHGBODY SMFIF_ADDRCPT SMFIF_DELRCPT SMFIF_CHGHDRS 
SMFIF_QUARANTINE SMFIF_CHGFROM SMFIF_ADDRCPT_PAR SMFIF_SETSYMLIST
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter8_conn_event: milter 
inet:localhost:11332: connect talvi.dovecot.org/94.237.25.159
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: event: SMFIC_CONNECT; 
macros: j=genome.ku.dk {daemon_name}=genome.ku.dk 
{daemon_addr}=192.168.3.112 v=Postfix 3.3.3
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: skipping reply for event 
SMFIC_CONNECT from milter inet:localhost:11332
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 220 genome.ku.dk ESMTP Postfix
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: < 
talvi.dovecot.org[94.237.25.159]: EHLO talvi.dovecot.org
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: report helo to all milters
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter_macro_lookup: 
"{tls_version}"
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter_macro_lookup: "{cipher}"
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter_macro_lookup: 
"{cipher_bits}"
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter_macro_lookup: 
"{cert_subject}"
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter_macro_lookup: 
"{cert_issuer}"
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter8_helo_event: milter 
inet:localhost:11332: helo talvi.dovecot.org
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: event: SMFIC_HELO; macros: 
(none)
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: skipping reply for event 
SMFIC_HELO from milter inet:localhost:11332
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: match_list_match: 
talvi.dovecot.org: no match
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: match_list_match: 
94.237.25.159: no match
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 250-genome.ku.dk
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 250-PIPELINING
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 250-SIZE 150000000
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 250-VRFY
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 250-ETRN
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 250-STARTTLS
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 250-ENHANCEDSTATUSCODES
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 250-8BITMIME
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 250 DSN
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: < 
talvi.dovecot.org[94.237.25.159]: STARTTLS
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: query milter states for 
other event
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter8_other_event: milter 
inet:localhost:11332
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 220 2.0.0 Ready to start TLS
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: abort all milters
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter8_abort: abort milter 
inet:localhost:11332
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: auto_clnt_open: connected to 
private/tlsmgr
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: send attr request = seed
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: send attr size = 32
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: private/tlsmgr: wanted 
attribute: status
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute name: status
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute value: 0
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: private/tlsmgr: wanted 
attribute: seed
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute name: seed
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute value: 
HIDDEN_BEFORE_POST
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: private/tlsmgr: wanted 
attribute: (list terminator)
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute name: (end)
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: send attr request = tktkey
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: send attr keyname = [data 16 
bytes]
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: private/tlsmgr: wanted 
attribute: status
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute name: status
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute value: 
4294967295
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: private/tlsmgr: wanted 
attribute: keybuf
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute name: keybuf
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute value: 
HIDDEN_BEFORE_POST
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: private/tlsmgr: wanted 
attribute: (list terminator)
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute name: (end)
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: send attr request = tktkey
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: send attr keyname = [data 0 
bytes]
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: private/tlsmgr: wanted 
attribute: status
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute name: status
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute value: 0
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: private/tlsmgr: wanted 
attribute: keybuf
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute name: keybuf
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute value: 
HIDDEN_BEFORE_POST
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: private/tlsmgr: wanted 
attribute: (list terminator)
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute name: (end)
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: Anonymous TLS connection 
established from talvi.dovecot.org[94.237.25.159]: TLSv1.2 with cipher 
ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: xsasl_dovecot_server_create: 
SASL service=smtp, realm=(null)
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: name_mask: noanonymous
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: 
xsasl_dovecot_server_connect: Connecting
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: 
xsasl_dovecot_server_connect: auth reply: VERSION?1?1
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: 
xsasl_dovecot_server_connect: auth reply: MECH?PLAIN?plaintext
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: name_mask: plaintext
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: 
xsasl_dovecot_server_connect: auth reply: MECH?LOGIN?plaintext
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: name_mask: plaintext
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: 
xsasl_dovecot_server_connect: auth reply: SPID?62898
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: 
xsasl_dovecot_server_connect: auth reply: CUID?107
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: 
xsasl_dovecot_server_connect: auth reply: COOKIE?HIDDEN_BEFORE_POST
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: 
xsasl_dovecot_server_connect: auth reply: DONE
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: 
xsasl_dovecot_server_mech_filter: keep mechanism: PLAIN
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: 
xsasl_dovecot_server_mech_filter: keep mechanism: LOGIN
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: < 
talvi.dovecot.org[94.237.25.159]: QUIT
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 221 2.0.0 Bye
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: match_hostname: 
smtpd_client_event_limit_exceptions: talvi.dovecot.org ~? 192.168.3.0/24
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: match_hostaddr: 
smtpd_client_event_limit_exceptions: 94.237.25.159 ~? 192.168.3.0/24
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: match_hostname: 
smtpd_client_event_limit_exceptions: talvi.dovecot.org ~? 127.0.0.0/8
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: match_hostaddr: 
smtpd_client_event_limit_exceptions: 94.237.25.159 ~? 127.0.0.0/8
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: match_list_match: 
talvi.dovecot.org: no match
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: match_list_match: 
94.237.25.159: no match
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: send attr request = disconnect
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: send attr ident = 
smtp:94.237.25.159
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: private/anvil: wanted 
attribute: status
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute name: status
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute value: 0
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: private/anvil: wanted 
attribute: (list terminator)
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: input attribute name: (end)
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: disconnect event to all milters
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: milter8_disc_event: quit 
milter inet:localhost:11332
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: disconnect from 
talvi.dovecot.org[94.237.25.159] ehlo=1 starttls=1 quit=1 commands=3
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: free all milters
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: free milter inet:localhost:11332
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: master_notify: status 1
Mar 28 10:57:42 dna01 postfix/smtpd[66648]: connection closed
Mar 28 10:57:47 dna01 postfix/smtpd[66648]: auto_clnt_close: disconnect 
private/tlsmgr stream
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: connection established
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: master_notify: status 0
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: name_mask: resource
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: name_mask: software
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: connect from 
talvi.dovecot.org[94.237.25.159]
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: match_list_match: 
talvi.dovecot.org: no match
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: match_list_match: 
94.237.25.159: no match
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: match_list_match: 
talvi.dovecot.org: no match
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: match_list_match: 
94.237.25.159: no match
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: smtp_stream_setup: 
maxtime=300 enable_deadline=0
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: match_hostname: 
smtpd_client_event_limit_exceptions: talvi.dovecot.org ~? 192.168.3.0/24
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: match_hostaddr: 
smtpd_client_event_limit_exceptions: 94.237.25.159 ~? 192.168.3.0/24
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: match_hostname: 
smtpd_client_event_limit_exceptions: talvi.dovecot.org ~? 127.0.0.0/8
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: match_hostaddr: 
smtpd_client_event_limit_exceptions: 94.237.25.159 ~? 127.0.0.0/8
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: match_list_match: 
talvi.dovecot.org: no match
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: match_list_match: 
94.237.25.159: no match
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: send attr request = connect
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: send attr ident = 
smtp:94.237.25.159
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: private/anvil: wanted 
attribute: status
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: input attribute name: status
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: input attribute value: 0
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: private/anvil: wanted 
attribute: count
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: input attribute name: count
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: input attribute value: 1
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: private/anvil: wanted 
attribute: rate
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: input attribute name: rate
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: input attribute value: 2
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: private/anvil: wanted 
attribute: (list terminator)
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: input attribute name: (end)
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: report connect to all milters
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter_macro_lookup: "j"
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter_macro_lookup: result 
"genome.ku.dk"
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter_macro_lookup: 
"{daemon_name}"
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter_macro_lookup: result 
"genome.ku.dk"
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter_macro_lookup: 
"{daemon_addr}"
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter_macro_lookup: result 
"192.168.3.112"
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter_macro_lookup: "v"
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter_macro_lookup: result 
"Postfix 3.3.3"
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter8_connect: 
non-protocol events for protocol version 6:
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter8_connect: 
transport=inet endpoint=localhost:11332
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: trying... [127.0.0.1]
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: vstream_tweak_tcp: 
TCP_MAXSEG 32741
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: fd=11: stream buffer size 
old=0 new=65482
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter8_connect: my_version=0x6
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter8_connect: 
my_actions=0x1ff SMFIF_ADDHDRS SMFIF_CHGBODY SMFIF_ADDRCPT SMFIF_DELRCPT 
SMFIF_CHGHDRS SMFIF_QUARANTINE SMFIF_CHGFROM SMFIF_ADDRCPT_PAR 
SMFIF_SETSYMLIST
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: 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
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter8_connect: milter 
inet:localhost:11332 version 6
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter8_connect: events 
SMFIP_NR_HDR SMFIP_NR_CONN SMFIP_NR_HELO SMFIP_NR_MAIL SMFIP_NR_RCPT 
SMFIP_NR_DATA SMFIP_NR_UNKN SMFIP_NR_EOH SMFIP_NR_BODY
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter8_connect: requests 
SMFIF_ADDHDRS SMFIF_CHGBODY SMFIF_ADDRCPT SMFIF_DELRCPT SMFIF_CHGHDRS 
SMFIF_QUARANTINE SMFIF_CHGFROM SMFIF_ADDRCPT_PAR SMFIF_SETSYMLIST
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter8_conn_event: milter 
inet:localhost:11332: connect talvi.dovecot.org/94.237.25.159
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: event: SMFIC_CONNECT; 
macros: j=genome.ku.dk {daemon_name}=genome.ku.dk 
{daemon_addr}=192.168.3.112 v=Postfix 3.3.3
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: skipping reply for event 
SMFIC_CONNECT from milter inet:localhost:11332
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 220 genome.ku.dk ESMTP Postfix
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: < 
talvi.dovecot.org[94.237.25.159]: EHLO talvi.dovecot.org
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: report helo to all milters
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter_macro_lookup: 
"{tls_version}"
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter_macro_lookup: "{cipher}"
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter_macro_lookup: 
"{cipher_bits}"
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter_macro_lookup: 
"{cert_subject}"
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter_macro_lookup: 
"{cert_issuer}"
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter8_helo_event: milter 
inet:localhost:11332: helo talvi.dovecot.org
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: event: SMFIC_HELO; macros: 
(none)
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: skipping reply for event 
SMFIC_HELO from milter inet:localhost:11332
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: match_list_match: 
talvi.dovecot.org: no match
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: match_list_match: 
94.237.25.159: no match
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 250-genome.ku.dk
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 250-PIPELINING
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 250-SIZE 150000000
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 250-VRFY
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 250-ETRN
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 250-STARTTLS
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 250-ENHANCEDSTATUSCODES
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 250-8BITMIME
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 250 DSN
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: < 
talvi.dovecot.org[94.237.25.159]: STARTTLS
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: query milter states for 
other event
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter8_other_event: milter 
inet:localhost:11332
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 220 2.0.0 Ready to start TLS
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: abort all milters
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter8_abort: abort milter 
inet:localhost:11332
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: auto_clnt_open: connected to 
private/tlsmgr
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: send attr request = seed
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: send attr size = 32
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: private/tlsmgr: wanted 
attribute: status
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: input attribute name: status
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: input attribute value: 0
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: private/tlsmgr: wanted 
attribute: seed
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: input attribute name: seed
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: input attribute value: 
HIDDEN_BEFORE_POST
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: private/tlsmgr: wanted 
attribute: (list terminator)
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: input attribute name: (end)
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: Anonymous TLS connection 
established from talvi.dovecot.org[94.237.25.159]: TLSv1.2 with cipher 
ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: xsasl_dovecot_server_create: 
SASL service=smtp, realm=(null)
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: name_mask: noanonymous
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: 
xsasl_dovecot_server_mech_filter: keep mechanism: PLAIN
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: 
xsasl_dovecot_server_mech_filter: keep mechanism: LOGIN
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: < 
talvi.dovecot.org[94.237.25.159]: QUIT
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: > 
talvi.dovecot.org[94.237.25.159]: 221 2.0.0 Bye
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: match_hostname: 
smtpd_client_event_limit_exceptions: talvi.dovecot.org ~? 192.168.3.0/24
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: match_hostaddr: 
smtpd_client_event_limit_exceptions: 94.237.25.159 ~? 192.168.3.0/24
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: match_hostname: 
smtpd_client_event_limit_exceptions: talvi.dovecot.org ~? 127.0.0.0/8
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: match_hostaddr: 
smtpd_client_event_limit_exceptions: 94.237.25.159 ~? 127.0.0.0/8
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: match_list_match: 
talvi.dovecot.org: no match
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: match_list_match: 
94.237.25.159: no match
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: send attr request = disconnect
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: send attr ident = 
smtp:94.237.25.159
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: private/anvil: wanted 
attribute: status
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: input attribute name: status
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: input attribute value: 0
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: private/anvil: wanted 
attribute: (list terminator)
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: input attribute name: (end)
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: disconnect event to all milters
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: milter8_disc_event: quit 
milter inet:localhost:11332
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: disconnect from 
talvi.dovecot.org[94.237.25.159] ehlo=1 starttls=1 quit=1 commands=3
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: free all milters
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: free milter inet:localhost:11332
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: master_notify: status 1
Mar 28 10:57:55 dna01 postfix/smtpd[66648]: connection closed
Mar 28 10:58:00 dna01 postfix/smtpd[66648]: auto_clnt_close: disconnect 
private/tlsmgr stream

-- 
Christian Anthon

More information about the dovecot mailing list