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
Fixed, we had smtp_security_level=verify, which I forgot to remove when we removed the relay server we used. Now it should work. Aki On 28.3.2019 13.59, Christian Anthon via dovecot wrote:> 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 >
Ahh, I had made some changes to our own server around the time, but couldn't for the life of me understand why it would selectively refuse to talk to the dovecot mailing list server. Thanks for clearing it up so promptly. Cheers, Christian. On 28/03/2019 13.21, Aki Tuomi wrote:> Fixed, we had smtp_security_level=verify, which I forgot to remove when > we removed the relay server we used. Now it should work. > > Aki > > On 28.3.2019 13.59, Christian Anthon via dovecot wrote: >> 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