postfix-users April 2011 archive
Main Archive Page > Month Archives  > postfix-users archives
postfix-users: Re: SASL

Re: SASL

From: Tolga <tolga_at_nospam>
Date: Mon Apr 11 2011 - 20:14:10 GMT
To: Postfix users <postfix-users@postfix.org>

11-04-2011 19:41, Wietse Venema yazmış:
> Tolga:
>> mail.log:
>> [snip]
>> Apr 11 16:25:39 216235 postfix/smtpd[24241]: connect from
>> unknown[193.255.135.1]
>> Apr 11 16:25:40 216235 postfix/smtpd[24241]: NOQUEUE: reject: RCPT from
>> unknown[193.255.135.1]: 554 5.7.1<mtozses@sabanciuniv.edu>: Relay
>> access denied; from=<bilgi@bilgisayarciniz.org>
>> to=<mtozses@sabanciuniv.edu> proto=ESMTP helo=<mtozses.sabanciuniv.edu>
>> Apr 11 16:25:42 216235 postfix/smtpd[24241]: disconnect from
>> unknown[193.255.135.1]
> As root, turn on debug logging for 193.255.135.1:
>
> # postconf -e debug_peer_list=193.255.135.1
> # postfix reload
>
> Then, try again, and see if the client sends the "AUTH" command.
> If it doesn't, then that is your problem.
>
> PS The AUTH PLAIN and AUTH LOGIN commands don't encrypt the password
> (it's just BASE64 encoded), so you don't want to post that.
>
> Wietse
Apr 11 20:11:18 216235 postfix/smtpd[25666]: warning: 212.253.85.253:
address not listed for hostname kunduz.org
Apr 11 20:11:18 216235 postfix/smtpd[25666]: connect from
unknown[212.253.85.253]
Apr 11 20:11:18 216235 postfix/smtpd[25666]: match_hostname: unknown ~?
127.0.0.0/8
Apr 11 20:11:18 216235 postfix/smtpd[25666]: match_hostaddr:
212.253.85.253 ~? 127.0.0.0/8
Apr 11 20:11:18 216235 postfix/smtpd[25666]: match_list_match: unknown:
no match
Apr 11 20:11:18 216235 postfix/smtpd[25666]: match_list_match:
212.253.85.253: no match
Apr 11 20:11:18 216235 postfix/smtpd[25666]: send attr request = connect
Apr 11 20:11:18 216235 postfix/smtpd[25666]: send attr ident =
submission:212.253.85.253
Apr 11 20:11:18 216235 postfix/smtpd[25666]: private/anvil: wanted
attribute: status
Apr 11 20:11:18 216235 postfix/smtpd[25666]: input attribute name: status
Apr 11 20:11:18 216235 postfix/smtpd[25666]: input attribute value: 0
Apr 11 20:11:18 216235 postfix/smtpd[25666]: private/anvil: wanted
attribute: count
Apr 11 20:11:18 216235 postfix/smtpd[25666]: input attribute name: count
Apr 11 20:11:18 216235 postfix/smtpd[25666]: input attribute value: 1
Apr 11 20:11:18 216235 postfix/smtpd[25666]: private/anvil: wanted
attribute: rate
Apr 11 20:11:18 216235 postfix/smtpd[25666]: input attribute name: rate
Apr 11 20:11:18 216235 postfix/smtpd[25666]: input attribute value: 1
Apr 11 20:11:18 216235 postfix/smtpd[25666]: private/anvil: wanted
attribute: (list terminator)
Apr 11 20:11:18 216235 postfix/smtpd[25666]: input attribute name: (end)
Apr 11 20:11:18 216235 postfix/smtpd[25666]: > unknown[212.253.85.253]:
220 mail.bilgisayarciniz.org ESMTP Postfix (2.7.0)
Apr 11 20:11:18 216235 postfix/smtpd[25666]:
xsasl_dovecot_server_create: SASL service=smtp, realm=(null)
Apr 11 20:11:18 216235 postfix/smtpd[25666]: name_mask: noanonymous
Apr 11 20:11:18 216235 postfix/smtpd[25666]:
xsasl_dovecot_server_mech_filter: keep mechanism: PLAIN
Apr 11 20:11:18 216235 postfix/smtpd[25666]: watchdog_pat: 0xb895e020
Apr 11 20:11:19 216235 postfix/smtpd[25666]: < unknown[212.253.85.253]:
EHLO [192.168.2.2]
Apr 11 20:11:19 216235 postfix/smtpd[25666]: > unknown[212.253.85.253]:
250-mail.bilgisayarciniz.org
Apr 11 20:11:19 216235 postfix/smtpd[25666]: > unknown[212.253.85.253]:
250-PIPELINING
Apr 11 20:11:19 216235 postfix/smtpd[25666]: > unknown[212.253.85.253]:
250-SIZE 10240000
Apr 11 20:11:19 216235 postfix/smtpd[25666]: > unknown[212.253.85.253]:
250-VRFY
Apr 11 20:11:19 216235 postfix/smtpd[25666]: > unknown[212.253.85.253]:
250-ETRN
Apr 11 20:11:19 216235 postfix/smtpd[25666]: match_list_match: unknown:
no match
Apr 11 20:11:19 216235 postfix/smtpd[25666]: match_list_match:
212.253.85.253: no match
Apr 11 20:11:19 216235 postfix/smtpd[25666]: > unknown[212.253.85.253]:
250-AUTH PLAIN
Apr 11 20:11:19 216235 postfix/smtpd[25666]: > unknown[212.253.85.253]:
250-ENHANCEDSTATUSCODES
Apr 11 20:11:19 216235 postfix/smtpd[25666]: > unknown[212.253.85.253]:
250-8BITMIME
Apr 11 20:11:19 216235 postfix/smtpd[25666]: > unknown[212.253.85.253]:
250 DSN
Apr 11 20:11:19 216235 postfix/smtpd[25666]: watchdog_pat: 0xb895e020
Apr 11 20:11:19 216235 postfix/smtpd[25666]: < unknown[212.253.85.253]:
AUTH PLAIN AGJpbGdpQGJpbGdpc2F5YXJjaW5pei5vcmcAYmlsZ2k5ODc=
Apr 11 20:11:19 216235 postfix/smtpd[25666]: xsasl_dovecot_server_first:
sasl_method PLAIN, init_response
AGJpbGdpQGJpbGdpc2F5YXJjaW5pei5vcmcAYmlsZ2k5ODc=
Apr 11 20:11:19 216235 postfix/smtpd[25666]: xsasl_dovecot_handle_reply:
auth reply: OK?2?user=bilgi@bilgisayarciniz.org
Apr 11 20:11:19 216235 postfix/smtpd[25666]: > unknown[212.253.85.253]:
235 2.7.0 Authentication successful
Apr 11 20:11:19 216235 postfix/smtpd[25666]: watchdog_pat: 0xb895e020
Apr 11 20:11:19 216235 postfix/smtpd[25666]: < unknown[212.253.85.253]:
MAIL FROM:<bilgi@bilgisayarciniz.org> SIZE=427
Apr 11 20:11:19 216235 postfix/smtpd[25666]: extract_addr: input:
<bilgi@bilgisayarciniz.org>
Apr 11 20:11:19 216235 postfix/smtpd[25666]: smtpd_check_addr:
addr=bilgi@bilgisayarciniz.org
Apr 11 20:11:19 216235 postfix/smtpd[25666]: ctable_locate: move
existing entry key bilgi@bilgisayarciniz.org
Apr 11 20:11:19 216235 postfix/smtpd[25666]: extract_addr: in:
<bilgi@bilgisayarciniz.org>, result: bilgi@bilgisayarciniz.org
Apr 11 20:11:19 216235 postfix/smtpd[25666]: fsspace: .: block size
4096, blocks free 12855363
Apr 11 20:11:19 216235 postfix/smtpd[25666]: smtpd_check_queue: blocks
4096 avail 12855363 min_free 0 msg_size_limit 10240000
Apr 11 20:11:19 216235 postfix/smtpd[25666]: > unknown[212.253.85.253]:
250 2.1.0 Ok
Apr 11 20:11:19 216235 postfix/smtpd[25666]: watchdog_pat: 0xb895e020
Apr 11 20:11:19 216235 postfix/smtpd[25666]: < unknown[212.253.85.253]:
RCPT TO:<tolga@ozses.net>
Apr 11 20:11:19 216235 postfix/smtpd[25666]: extract_addr: input:
<tolga@ozses.net>
Apr 11 20:11:19 216235 postfix/smtpd[25666]: smtpd_check_addr:
addr=tolga@ozses.net
Apr 11 20:11:19 216235 postfix/smtpd[25666]: ctable_locate: move
existing entry key tolga@ozses.net
Apr 11 20:11:19 216235 postfix/smtpd[25666]: extract_addr: in:
<tolga@ozses.net>, result: tolga@ozses.net
Apr 11 20:11:19 216235 postfix/smtpd[25666]: >>> START Client host
RESTRICTIONS <<<
Apr 11 20:11:19 216235 postfix/smtpd[25666]: generic_checks:
name=permit_sasl_authenticated
Apr 11 20:11:19 216235 postfix/smtpd[25666]: generic_checks:
name=permit_sasl_authenticated status=1
Apr 11 20:11:19 216235 postfix/smtpd[25666]: >>> START Recipient address
RESTRICTIONS <<<
Apr 11 20:11:19 216235 postfix/smtpd[25666]: generic_checks:
name=permit_mynetworks
Apr 11 20:11:19 216235 postfix/smtpd[25666]: permit_mynetworks: unknown
212.253.85.253
Apr 11 20:11:19 216235 postfix/smtpd[25666]: match_hostname: unknown ~?
127.0.0.0/8
Apr 11 20:11:19 216235 postfix/smtpd[25666]: match_hostaddr:
212.253.85.253 ~? 127.0.0.0/8
Apr 11 20:11:19 216235 postfix/smtpd[25666]: match_list_match: unknown:
no match
Apr 11 20:11:19 216235 postfix/smtpd[25666]: match_list_match:
212.253.85.253: no match
Apr 11 20:11:19 216235 postfix/smtpd[25666]: generic_checks:
name=permit_mynetworks status=0
Apr 11 20:11:19 216235 postfix/smtpd[25666]: generic_checks:
name=reject_unauth_destination
Apr 11 20:11:19 216235 postfix/smtpd[25666]: reject_unauth_destination:
tolga@ozses.net
Apr 11 20:11:19 216235 postfix/smtpd[25666]: permit_auth_destination:
tolga@ozses.net
Apr 11 20:11:19 216235 postfix/smtpd[25666]: ctable_locate: leave
existing entry key tolga@ozses.net
Apr 11 20:11:19 216235 postfix/smtpd[25666]: NOQUEUE: reject: RCPT from
unknown[212.253.85.253]: 554 5.7.1 <tolga@ozses.net>: Relay access
denied; from=<bilgi@bilgisayarciniz.org> to=<tolga@ozses.net>
proto=ESMTP helo=<[192.168.2.2]>
Apr 11 20:11:19 216235 postfix/smtpd[25666]: generic_checks:
name=reject_unauth_destination status=2
Apr 11 20:11:19 216235 postfix/smtpd[25666]: > unknown[212.253.85.253]:
554 5.7.1 <tolga@ozses.net>: Relay access denied
Apr 11 20:11:19 216235 postfix/smtpd[25666]: watchdog_pat: 0xb895e020
Apr 11 20:11:22 216235 postfix/smtpd[25666]: < unknown[212.253.85.253]: QUIT
Apr 11 20:11:22 216235 postfix/smtpd[25666]: > unknown[212.253.85.253]:
221 2.0.0 Bye
Apr 11 20:11:22 216235 postfix/smtpd[25666]: match_hostname: unknown ~?
127.0.0.0/8
Apr 11 20:11:22 216235 postfix/smtpd[25666]: match_hostaddr:
212.253.85.253 ~? 127.0.0.0/8
Apr 11 20:11:22 216235 postfix/smtpd[25666]: match_list_match: unknown:
no match
Apr 11 20:11:22 216235 postfix/smtpd[25666]: match_list_match:
212.253.85.253: no match
Apr 11 20:11:22 216235 postfix/smtpd[25666]: send attr request = disconnect
Apr 11 20:11:22 216235 postfix/smtpd[25666]: send attr ident =
submission:212.253.85.253
Apr 11 20:11:22 216235 postfix/smtpd[25666]: private/anvil: wanted
attribute: status
Apr 11 20:11:22 216235 postfix/smtpd[25666]: input attribute name: status
Apr 11 20:11:22 216235 postfix/smtpd[25666]: input attribute value: 0
Apr 11 20:11:22 216235 postfix/smtpd[25666]: private/anvil: wanted
attribute: (list terminator)
Apr 11 20:11:22 216235 postfix/smtpd[25666]: input attribute name: (end)
Apr 11 20:11:22 216235 postfix/smtpd[25666]: disconnect from
unknown[212.253.85.253]

This is from another location. The same thing happened here too.
Regards,
Tolga