On Tue, 9 Mar 2010 19:19:28 -0500
Victor Duchovni <[email protected]> replied:
>Is this the MUA? Does the MUA bother to send QUIT, or does it just
>disconnect? You can use "debug_peer_list" to see what this client
>is up to after it sends mail.
OK Victor, this is the complete output of "debug_peer_list" with
appropriate entries disguised. I changed the password prior to sending so
it would not be a security problem. I realize that this is probably way
more than you wanted; however, I did not want to leave any important
information out.
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: connect from
xxxxxx.my_domain.com[192.168.1.101]
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: match_hostname:
xxxxxx.my_domain.com ~? 127.0.0.0/8
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: match_hostaddr: 192.168.1.101 ~?
127.0.0.0/8
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: match_hostname:
xxxxxx.my_domain.com ~? 192.168.1.0/24
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: match_hostaddr: 192.168.1.101 ~?
192.168.1.0/24
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: report connect to all milters
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: "j"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: result
"xxxxxx.my_domain.com"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup:
"{daemon_name}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: result
"ORIGINATING"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: "v"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: result
"Postfix 2.7-20100117"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter8_connect: non-protocol
events for protocol version 6:
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter8_connect: transport=unix
endpoint=/var/run/clamav/clmilter.sock
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter8_connect: my_version=0x6
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: 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 10 04:59:46 xxxxxxx postfix/smtpd[93352]: 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 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter8_connect: milter
unix:/var/run/clamav/clmilter.sock version 6
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter8_connect: events
SMFIP_NOHELO SMFIP_NOEOH SMFIP_NOUNKNOWN SMFIP_NODATA
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter8_connect: requests
SMFIF_ADDHDRS SMFIF_QUARANTINE
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter8_conn_event: milter
unix:/var/run/clamav/clmilter.sock: connect xxxxxx.my_domain.com/192.168.1.101
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: event: SMFIC_CONNECT; macros:
j=xxxxxx.my_domain.com {daemon_name}=ORIGINATING v=Postfix 2.7-20100117
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: reply: SMFIR_ACCEPT data 0 bytes
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 220 xxxxxx.my_domain.com ESMTP Postfix
(2.7-20100117)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: watchdog_pat: 0x28607d88
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: <
xxxxxx.my_domain.com[192.168.1.101]: EHLO xxxxxx.my_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: report helo to all milters
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup:
"{tls_version}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: "{cipher}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup:
"{cipher_bits}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup:
"{cert_subject}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup:
"{cert_issuer}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter8_helo_event: skip milter
unix:/var/run/clamav/clmilter.sock
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250-xxxxxx.my_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250-PIPELINING
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250-SIZE 10240000
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250-ETRN
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: match_hostname:
xxxxxx.my_domain.com ~? 127.0.0.0/8
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: match_hostaddr: 192.168.1.101 ~?
127.0.0.0/8
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: match_hostname:
xxxxxx.my_domain.com ~? 192.168.1.0/24
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: match_hostaddr: 192.168.1.101 ~?
192.168.1.0/24
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250-STARTTLS
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250-XVERP
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250-ENHANCEDSTATUSCODES
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250-8BITMIME
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250 DSN
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: watchdog_pat: 0x28607d88
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: <
xxxxxx.my_domain.com[192.168.1.101]: STARTTLS
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: query milter states for other
event
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter8_other_event: milter
unix:/var/run/clamav/clmilter.sock
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 220 2.0.0 Ready to start TLS
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: abort all milters
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter8_abort: skip milter
unix:/var/run/clamav/clmilter.sock
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr request = seed
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr size = 32
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/tlsmgr: wanted attribute:
status
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: status
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value: 0
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/tlsmgr: wanted attribute:
seed
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: seed
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value:
Ss+VdY4crk1YNolMhFiFLlcQqwuHNfkRLcaiFyLEuDE=
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/tlsmgr: wanted attribute:
(list terminator)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: (end)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr request = update
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr cache_type = smtpd
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr cache_id =
1F230F5B582F44EBA99161DE4EA6DBDC728276215D95AE70681442B4A33E1D6C&s=submission
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr session = [data 127
bytes]
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/tlsmgr: wanted attribute:
status
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: status
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value: 0
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/tlsmgr: wanted attribute:
(list terminator)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: (end)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: xsasl_cyrus_server_create: SASL
service=smtp, realm=xxxxxx.my_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: name_mask: noanonymous
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: watchdog_pat: 0x28607d88
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: <
xxxxxx.my_domain.com[192.168.1.101]: EHLO xxxxxx.my_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: report helo to all milters
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup:
"{tls_version}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: result
"TLSv1"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: "{cipher}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: result
"DHE-RSA-AES128-SHA"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup:
"{cipher_bits}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: result "128"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup:
"{cert_subject}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup:
"{cert_issuer}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter8_helo_event: skip milter
unix:/var/run/clamav/clmilter.sock
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250-xxxxxx.my_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250-PIPELINING
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250-SIZE 10240000
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250-ETRN
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250-AUTH LOGIN PLAIN DIGEST-MD5 CRAM-MD5
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: match_hostname:
xxxxxx.my_domain.com ~? 127.0.0.0/8
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: match_hostaddr: 192.168.1.101 ~?
127.0.0.0/8
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: match_hostname:
xxxxxx.my_domain.com ~? 192.168.1.0/24
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: match_hostaddr: 192.168.1.101 ~?
192.168.1.0/24
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250-AUTH=LOGIN PLAIN DIGEST-MD5 CRAM-MD5
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250-XVERP
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250-ENHANCEDSTATUSCODES
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250-8BITMIME
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250 DSN
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: watchdog_pat: 0x28607d88
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: <
xxxxxx.my_domain.com[192.168.1.101]: AUTH CRAM-MD5
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: query milter states for other
event
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter8_other_event: milter
unix:/var/run/clamav/clmilter.sock
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: xsasl_cyrus_server_first:
sasl_method CRAM-MD5
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: xsasl_cyrus_server_auth_response:
uncoded server challenge: <[email protected]_domain.com>
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 334
PDI0MjMxNzM3NjQuOTkyNDA2MUBzY29ycGlvLnNlaWJlcmNvbS5uZXQ+
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: <
xxxxxx.my_domain.com[192.168.1.101]:
Z2VyYXJkIDM4ODg0YmZkMjI4OTQ2NTZkYjM2OGNhODBkYThlZmIx
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: xsasl_cyrus_server_next: decoded
response: user 38884bfd22894656db368ca80da8efb1
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 235 2.7.0 Authentication successful
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: watchdog_pat: 0x28607d88
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: <
xxxxxx.my_domain.com[192.168.1.101]: MAIL
FROM:<u...@my_domain.comuser@my_domain.com> SIZE=1495
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: extract_addr: input:
<u...@my_domain.com>
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: smtpd_check_addr:
addr=u...@my_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: connect to subsystem
private/rewrite
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr request = rewrite
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr rule = local
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr address =
u...@my_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: flags
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: flags
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value: 0
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: address
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: address
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value:
u...@my_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: (list terminator)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: (end)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: rewrite_clnt: local:
u...@my_domain.com -> u...@my_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr request = resolve
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr sender =
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr address =
u...@my_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: flags
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: flags
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value: 0
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: transport
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: transport
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value: dovecot
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: nexthop
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: nexthop
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value:
my_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: recipient
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: recipient
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value:
u...@my_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: flags
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: flags
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value: 1024
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: (list terminator)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: (end)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: resolve_clnt: `' ->
`u...@my_domain.com' -> transp=`dovecot' host=`my_domain.com'
rcpt=`u...@my_domain.com' flags= class=virtual
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: ctable_locate: install entry key
u...@my_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: extract_addr: in:
<u...@my_domain.com>, result: u...@my_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: report sender to all milters
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: "i"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: "{auth_type}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: result
"CRAM-MD5"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup:
"{auth_authen}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: result
"[email protected]_domain.com"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup:
"{auth_author}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: "{mail_addr}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: ctable_locate: leave existing
entry key u...@my_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: result
"u...@my_domain.com"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: "{mail_host}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: ctable_locate: leave existing
entry key u...@my_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: result
"my_domain.com"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup:
"{mail_mailer}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: ctable_locate: leave existing
entry key u...@my_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: result
"dovecot"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter8_mail_event: skip milter
unix:/var/run/clamav/clmilter.sock
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: fsspace: .: block size 2048,
blocks free 512223
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: smtpd_check_queue: blocks 2048
avail 512223 min_free 0 msg_size_limit 10240000
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250 2.1.0 Ok
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: watchdog_pat: 0x28607d88
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: <
xxxxxx.my_domain.com[192.168.1.101]: RCPT TO:<[email protected]>
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: extract_addr: input:
<[email protected]>
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: smtpd_check_addr:
[email protected]
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr request = rewrite
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr rule = local
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr address =
[email protected]
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: flags
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: flags
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value: 0
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: address
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: address
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value:
[email protected]
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: (list terminator)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: (end)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: rewrite_clnt: local:
[email protected] -> [email protected]
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr request = resolve
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr sender =
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr address =
[email protected]
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: flags
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: flags
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value: 0
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: transport
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: transport
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value: smtp
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: nexthop
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: nexthop
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value: ISP.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: recipient
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: recipient
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value:
[email protected]
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: flags
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: flags
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value: 4096
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: (list terminator)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: (end)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: resolve_clnt: `' ->
`[email protected]' -> transp=`smtp' host=`ISP.com' rcpt=`[email protected]'
flags= class=default
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: ctable_locate: install entry key
[email protected]
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: extract_addr: in:
<[email protected]>, result: [email protected]
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr request = rewrite
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr rule = local
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr address = double-bounce
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: flags
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: flags
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value: 0
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: address
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: address
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value:
double-bou...@my_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: private/rewrite socket: wanted
attribute: (list terminator)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: (end)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: rewrite_clnt: local:
double-bounce -> double-bou...@my_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >>> START Client host
RESTRICTIONS <<<
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: generic_checks:
name=permit_sasl_authenticated
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: generic_checks:
name=permit_sasl_authenticated status=1
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >>> START Recipient address
RESTRICTIONS <<<
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: generic_checks:
name=permit_sasl_authenticated
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: generic_checks:
name=permit_sasl_authenticated status=1
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >>> CHECKING RECIPIENT MAPS <<<
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: ctable_locate: leave existing
entry key [email protected]
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: maps_find:
recipient_canonical_maps: [email protected]: not found
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: match_list_match: ISP.com: no
match
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: maps_find:
recipient_canonical_maps: @ISP.com: not found
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: mail_addr_find: [email protected]
-> (not found)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: maps_find: canonical_maps:
[email protected]: not found
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: match_list_match: ISP.com: no
match
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: maps_find: canonical_maps:
@ISP.com: not found
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: mail_addr_find: [email protected]
-> (not found)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: maps_find: virtual_alias_maps:
[email protected]: not found
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: match_list_match: ISP.com: no
match
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: maps_find: virtual_alias_maps:
@ISP.com: not found
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: mail_addr_find: [email protected]
-> (not found)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: report recipient to all milters
(flags=0x0)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: "i"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: "{rcpt_addr}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: ctable_locate: leave existing
entry key [email protected]
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: result
"[email protected]"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: "{rcpt_host}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: ctable_locate: leave existing
entry key [email protected]
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: result
"ISP.com"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup:
"{rcpt_mailer}"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: ctable_locate: leave existing
entry key [email protected]
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: result "smtp"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter8_rcpt_event: skip milter
unix:/var/run/clamav/clmilter.sock
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: smtpd_check_rewrite: trying:
permit_inet_interfaces
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: permit_inet_interfaces:
xxxxxx.my_domain.com 192.168.1.101
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: before input_transp_cleanup:
cleanup flags = enable_header_body_filter enable_automatic_bcc
enable_address_mapping enable_milters
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: after input_transp_cleanup:
cleanup flags = enable_header_body_filter enable_automatic_bcc
enable_address_mapping enable_milters
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: connect to subsystem
public/cleanup
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: public/cleanup socket: wanted
attribute: queue_id
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: queue_id
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value: 9350E22831
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: public/cleanup socket: wanted
attribute: (list terminator)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: (end)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: send attr flags = 242
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: 9350E22831:
client=xxxxxx.my_domain.com[192.168.1.101], sasl_method=CRAM-MD5,
[email protected]_domain.com
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250 2.1.5 Ok
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: watchdog_pat: 0x28607d88
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: <
xxxxxx.my_domain.com[192.168.1.101]: DATA
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: report data to all milters
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: "i"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter_macro_lookup: result
"9350E22831"
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter8_data_event: skip milter
unix:/var/run/clamav/clmilter.sock
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 354 End data with <CR><LF>.<CR><LF>
Mar 10 04:59:46 xxxxxxx postfix/cleanup[93355]: 9350E22831:
message-id=<[email protected]_domain.com>
Mar 10 04:59:46 xxxxxxx postfix/qmgr[1317]: 9350E22831:
from=<u...@my_domain.com>, size=1889, nrcpt=1 (queue active)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: public/cleanup socket: wanted
attribute: status
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: status
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value: 0
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: public/cleanup socket: wanted
attribute: reason
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: reason
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute value: (end)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: public/cleanup socket: wanted
attribute: (list terminator)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: input attribute name: (end)
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 250 2.0.0 Ok: queued as 9350E22831
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: abort all milters
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter8_abort: skip milter
unix:/var/run/clamav/clmilter.sock
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: watchdog_pat: 0x28607d88
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: <
xxxxxx.my_domain.com[192.168.1.101]: QUIT
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: >
xxxxxx.my_domain.com[192.168.1.101]: 221 2.0.0 Bye
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: match_hostname:
xxxxxx.my_domain.com ~? 127.0.0.0/8
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: match_hostaddr: 192.168.1.101 ~?
127.0.0.0/8
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: match_hostname:
xxxxxx.my_domain.com ~? 192.168.1.0/24
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: match_hostaddr: 192.168.1.101 ~?
192.168.1.0/24
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: warning: network_biopair_interop:
error reading 5 bytes from the network: Connection reset by peer
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: abort all milters
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter8_abort: skip milter
unix:/var/run/clamav/clmilter.sock
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: disconnect event to all milters
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: milter8_disc_event: skip quit
milter unix:/var/run/clamav/clmilter.sock
Mar 10 04:59:46 xxxxxxx postfix/smtpd[93352]: disconnect from
xxxxxx.my_domain.com[192.168.1.101]
Mar 10 04:59:49 xxxxxxx postfix/smtp[93359]: 9350E22831:
to=<[email protected]>, relay=smtp.gmail.com[74.125.93.109]:25, delay=3,
delays=0.12/0.02/0.97/1.9, dsn=2.0.0, status=sent (250 2.0.0 OK 1268215189
5sm18267533qwg.53)
Mar 10 04:59:49 xxxxxxx postfix/qmgr[1317]: 9350E22831: removed
--
Jerry
[email protected]
TO REPORT A PROBLEM see http://www.postfix.org/DEBUG_README.html#mail
TO (UN)SUBSCRIBE see http://www.postfix.org/lists.html
Veni, Vidi, VISA: I came, I saw, I did a little shopping.
