Собственно, вот... Честно говоря, не очень хорошо понял, где можно найти косяк, хотя весьма информативно.
Код: Выделить всё
#exim -bd -d+all
10:13:15 2749 Exim version 4.69 (FreeBSD 6.2) uid=0 gid=0 pid=2749 D=fffdffff
Probably Berkeley DB version 1.8x (native mode)
Support for: crypteq iconv() use_setclassresources PAM Perl Expand_dlfunc OpenSSL Content_Scanning Old_Demime
Lookups: lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmnz dnsdb dsearch mysql nis nis0 passwd
Authenticators: cram_md5 dovecot plaintext spa
Routers: accept dnslookup ipliteral manualroute queryprogram redirect
Transports: appendfile/maildir/mailstore/mbx autoreply lmtp pipe smtp
Fixed never_users: 0
Size of off_t: 8
10:13:15 2749 changed uid/gid: forcing real = effective
10:13:15 2749 uid=0 gid=0 pid=2749
10:13:15 2749 auxiliary group list: 0
10:13:15 2749 seeking password data for user "mailnull": using cached result
10:13:15 2749 getpwnam() succeeded uid=26 gid=26
10:13:15 2749 seeking password data for user "root": cache not available
10:13:15 2749 getpwnam() succeeded uid=0 gid=0
10:13:15 2749 configuration file is /usr/local/etc/exim/configure
10:13:15 2749 log selectors = 0000cefe 00233801
10:13:15 2749 cwd=/www/home/www/administrator/postfixadmin 3 args: exim -bd -d+all
10:13:15 2749 trusted user
10:13:15 2749 admin user
10:13:15 2749 seeking password data for user "mailnull": cache not available
10:13:15 2749 getpwnam() succeeded uid=26 gid=26
10:13:15 2749 originator: uid=0 gid=0 login=root name=Charlie Root
10:13:15 2749 listening on all interfaces (IPv4) port 25
10:13:15 2749 listening on all interfaces (IPv4) port 465
10:13:15 2749 pid written to /var/run/exim.pid
10:13:15 2749 changed uid/gid: running as a daemon
10:13:15 2749 uid=26 gid=6 pid=2749
10:13:15 2749 auxiliary group list: 6 6
10:13:15 2749 LOG: MAIN
10:13:15 2749 exim 4.69 daemon started: pid=2749, no queue runs, listening for SMTP on port 25 (IPv4) and for SMTPS on port 465 (IPv4)
10:13:15 2749 set_process_info: 2749 daemon: no queue runs, listening for SMTP on port 25 (IPv4) and for SMTPS on port 465 (IPv4)
10:13:15 2749 daemon running with uid=26 gid=6 euid=26 egid=6
10:13:15 2749 Listening...
10:13:32 2749 Connection request from 193.xxx.xxx.xxx port 17244
10:13:32 2749 interface address=xxx.xxx.xxx.xxx port=25
10:13:32 2749 search_tidyup called
10:13:32 2749 1 SMTP accept process running
10:13:32 2749 Listening...
10:13:32 2751 sender_fullhost = [193.xxx.xxx.xxx]
10:13:32 2751 sender_rcvhost = [193.xxx.xxx.xxx]
10:13:32 2751 Process 2751 is handling incoming connection from [193.xxx.xxx.xxx]
10:13:32 2751 checking for IP options
10:13:32 2751 no IP options found
10:13:32 2751 host in host_lookup? yes (matched "*")
10:13:32 2751 looking up host name for 193.xxx.xxx.xxx
;; res_nquerydomain(xxx.xxx.xxx.193.in-addr.arpa, <Nil>, 1, 12)
;; res_query(xxx.xxx.xxx.193.in-addr.arpa, 1, 12)
;; res_nmkquery(QUERY, xxx.xxx.xxx.193.in-addr.arpa, IN, PTR)
;; res_send()
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 30477
;; flags: rd; QUERY: 1, ANSWER: 0, AUTHORITY: 0, ADDITIONAL: 0
;; xxx.xxx.xxx.193.in-addr.arpa, type = PTR, class = IN
;; Querying server (# 1) address = xxx.xxx.xxx.xxx
;; new DG socket
;; got answer:
;; ->>HEADER<<- opcode: QUERY, status: NXDOMAIN, id: 30477
;; flags: qr rd ra; QUERY: 1, ANSWER: 0, AUTHORITY: 1, ADDITIONAL: 0
;; 168.142.218.193.in-addr.arpa, type = PTR, class = IN
193.in-addr.arpa. 1h45m55s IN SOA ns-pri.ripe.net. dns-help.ripe.net. (
2008080521 ; serial
1H ; refresh
2H ; retry
2W ; expiry
2H ) ; minimum
;; rcode = (NXDOMAIN), counts = an:0 ns:1 ar:0
10:13:32 2751 DNS lookup of xxx.xxx.xxx.193.in-addr.arpa (PTR) gave HOST_NOT_FOUND
10:13:32 2751 returning DNS_NOMATCH
10:13:32 2751 IP address lookup using gethostbyaddr()
;; res_query(xxx.xxx.xxx.193.in-addr.arpa, 1, 12)
;; res_nmkquery(QUERY, xxx.xxx.xxx.193.in-addr.arpa, IN, PTR)
;; res_send()
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 30478
;; flags: rd; QUERY: 1, ANSWER: 0, AUTHORITY: 0, ADDITIONAL: 0
;; 168.142.218.193.in-addr.arpa, type = PTR, class = IN
;; Querying server (# 1) address = xxx.xxx.xxx.xxx
;; new DG socket
;; got answer:
;; ->>HEADER<<- opcode: QUERY, status: NXDOMAIN, id: 30478
;; flags: qr rd ra; QUERY: 1, ANSWER: 0, AUTHORITY: 1, ADDITIONAL: 0
;; 168.142.218.193.in-addr.arpa, type = PTR, class = IN
193.in-addr.arpa. 1h45m55s IN SOA ns-pri.ripe.net. dns-help.ripe.net. (
2008080521 ; serial
1H ; refresh
2H ; retry
2W ; expiry
2H ) ; minimum
;; rcode = (NXDOMAIN), counts = an:0 ns:1 ar:0
res_nquery failed (-1)
10:13:32 2751 IP address lookup failed: h_errno=1
10:13:32 2751 LOG: host_lookup_failed MAIN
10:13:32 2751 no host name found for IP address 193.xxx.xxx.xxx
10:13:32 2751 sender_fullhost = [193.xxx.xxx.xxx]
10:13:32 2751 sender_rcvhost = [193.xxx.xxx.xxx]
10:13:32 2751 set_process_info: 2751 handling incoming connection from [193.xxx.xxx.xxx]
10:13:32 2751 host in host_reject_connection? no (option unset)
10:13:32 2751 host in sender_unqualified_hosts? no (option unset)
10:13:32 2751 host in recipient_unqualified_hosts? no (option unset)
10:13:32 2751 host in helo_verify_hosts? no (option unset)
10:13:32 2751 host in helo_try_verify_hosts? no (option unset)
10:13:32 2751 host in helo_accept_junk_hosts? no (option unset)
10:13:32 2751 expanding: Welcome on our mail server!
10:13:32 2751 This system does not accept Unsolicited Commercial Email
10:13:32 2751 and will blacklist offenders via our spam processor.
10:13:32 2751 Have a nice day!
10:13:32 2751
10:13:32 2751 ${primary_hostname} ESMTP
10:13:32 2751 result: Welcome on our mail server!
10:13:32 2751 This system does not accept Unsolicited Commercial Email
10:13:32 2751 and will blacklist offenders via our spam processor.
10:13:32 2751 Have a nice day!
10:13:32 2751
10:13:32 2751 myhost.ru ESMTP
10:13:32 2751 SMTP>> 220-Welcome on our mail server!
10:13:32 2751 220-This system does not accept Unsolicited Commercial Email
10:13:32 2751 220-and will blacklist offenders via our spam processor.
10:13:32 2751 220-Have a nice day!
10:13:32 2751 220-
10:13:32 2751 220 myhost.ru ESMTP
10:13:32 2751 Process 2751 is ready for new message
10:13:32 2751 smtp_setup_msg entered
10:13:32 2751 SMTP<< EHLO [10.0.8.50]
10:13:32 2751 Actual local interface address is 212.xxx.xxx.xxx (bce0)
10:13:32 2751 Actual local interface address is 212.xxx.xxx.xxx (bce1)
10:13:32 2751 Actual local interface address is 127.0.0.1 (lo0)
10:13:32 2751 [10.0.8.50] in helo_lookup_domains? no (end of list)
10:13:32 2751 sender_fullhost = ([10.0.8.50]) [193.xxx.xxx.xxx]
10:13:32 2751 sender_rcvhost = [193.xxx.xxx.xxx] (helo=[10.0.8.50])
10:13:32 2751 set_process_info: 2751 handling incoming connection from ([10.0.8.50]) [193.xxx.xxx.xxx]
10:13:32 2751 host in pipelining_advertise_hosts? yes (matched "*")
10:13:32 2751 host in auth_advertise_hosts? yes (matched "*")
10:13:32 2751 host in tls_advertise_hosts? yes (matched "*")
10:13:32 2751 SMTP>> 250-myhost.ru Hello [10.0.8.50] [193.xxx.xxx.xxx]
10:13:32 2751 250-SIZE 10485760
10:13:32 2751 250-PIPELINING
10:13:32 2751 250-AUTH PLAIN LOGIN CRAM-MD5
10:13:32 2751 250-STARTTLS
10:13:32 2751 250 HELP
10:13:36 2751 SMTP<< AUTH CRAM-MD5
10:13:36 2751 SMTP>> 334 PDI3NTEuMTIxNzkwOTYxNkBwcmVzcy5rej4=
10:13:36 2751 SMTP<< bWFpbEBidWhnYWx0ZXIua3ogNjYyMzU2MWNlNjhiYWQ2N2YwYWVlMGFmYjQyYWZhMjM=
10:13:36 2751 expanding: $1
10:13:36 2751 result: mail@my-virtualhost.ru
10:13:36 2751 expanding: SELECT password FROM mailbox WHERE username = '${quote_mysql:$1}' AND active = '1'
10:13:36 2751 result: SELECT password FROM mailbox WHERE username = 'mail@my-virtualhost.ru' AND active = '1'
10:13:36 2751 search_open: mysql "NULL"
10:13:36 2751 search_find: file="NULL"
10:13:36 2751 key="SELECT password FROM mailbox WHERE username = 'mail@my-virtualhost.ru' AND active = '1'" partial=-1 affix=NULL starflags=0
10:13:36 2751 LRU list:
10:13:36 2751 internal_search_find: file="NULL"
10:13:36 2751 type=mysql key="SELECT password FROM mailbox WHERE username = 'mail@my-virtualhost.ru' AND active = '1'"
10:13:36 2751 database lookup required for SELECT password FROM mailbox WHERE username = 'mail@my-virtualhost.ru' AND active = '1'
10:13:36 2751 MySQL query: SELECT password FROM mailbox WHERE username = 'mail@my-virtualhost.ru' AND active = '1'
10:13:36 2751 MYSQL new connection: host=localhost port=0 socket=NULL database=exim user=exim
10:13:36 2751 lookup yielded: $1$12f38753$ldFNsedpgMIZtmtFlqsur/
10:13:36 2751 expanding: $value
10:13:36 2751 result: $1$12f38753$ldFNsedpgMIZtmtFlqsur/
10:13:36 2751 expanding: ${lookup mysql{SELECT password FROM mailbox WHERE username = '${quote_mysql:$1}' AND active = '1'}{$value}fail}
10:13:36 2751 result: $1$12f38753$ldFNsedpgMIZtmtFlqsur/
10:13:36 2751 CRAM-MD5: user name = mail@my-virtualhost.ru
10:13:36 2751 challenge = <2751.1217909616@myhost.ru>
10:13:36 2751 received = 6623561ce68bad67f0aee0afb42afa23
10:13:36 2751 digest = da2d93db8463f028879ca317e4c397c3
10:13:36 2751 expanding: $1
10:13:36 2751 result: mail@my-virtualhost.ru
10:13:36 2751 SMTP>> 535 Incorrect authentication data
10:13:36 2751 LOG: MAIN REJECT
10:13:36 2751 auth_cram_md5 authenticator failed for ([10.0.8.50]) [193.xxx.xxx.xxx]: 535 Incorrect authentication data (set_id=mail@my-virtualhost.ru)
10:13:37 2751 SMTP<< AUTH PLAIN AG1haWxAYnVoZ2FsdGVyLmt6AHJmZTU2MDI=
10:13:37 2751 host in smtp_accept_max_nonmail_hosts? yes (matched "*")
10:13:37 2751 auth_plain authenticator:
10:13:37 2751 $auth1 =
10:13:37 2751 $auth2 = mail@my-virtualhost.ru
10:13:37 2751 $auth3 = password
10:13:37 2751 $1 =
10:13:37 2751 $2 = mail@my-virtualhost.ru
10:13:37 2751 $3 = password
10:13:37 2751 expanding: $3
10:13:37 2751 result: password
10:13:37 2751 expanding: $2
10:13:37 2751 result: mail@my-virtualhost.ru
10:13:37 2751 expanding: $3
10:13:37 2751 result: password
10:13:37 2751 expanding: SELECT username FROM mailbox WHERE username = '${quote_mysql:$2}' AND password = '${quote_mysql:$3}' AND active = '1'
10:13:37 2751 result: SELECT username FROM mailbox WHERE username = 'mail@my-virtualhost.ru' AND password = 'password' AND active = '1'
10:13:37 2751 search_open: mysql "NULL"
10:13:37 2751 cached open
10:13:37 2751 search_find: file="NULL"
10:13:37 2751 key="SELECT username FROM mailbox WHERE username = 'mail@my-virtualhost.ru' AND password = 'password' AND active = '1'" partial=-1 affix=NULL starflags=0
10:13:37 2751 LRU list:
10:13:37 2751 internal_search_find: file="NULL"
10:13:37 2751 type=mysql key="SELECT username FROM mailbox WHERE username = 'mail@my-virtualhost.ru' AND password = 'password' AND active = '1'"
10:13:37 2751 database lookup required for SELECT username FROM mailbox WHERE username = 'mail@my-virtualhost.ru' AND password = 'password' AND active = '1'
10:13:37 2751 MySQL query: SELECT username FROM mailbox WHERE username = 'mail@my-virtualhost.ru' AND password = 'password' AND active = '1'
10:13:37 2751 MYSQL using cached connection for localhost/exim/exim
10:13:37 2751 MYSQL: no data found
10:13:37 2751 lookup failed
10:13:37 2751 expanding: value
10:13:37 2751 result: value
10:13:37 2751 skipping: result is not used
10:13:37 2751 expanding: *
10:13:37 2751 result: *
10:13:37 2751 expanding: ${lookup mysql{SELECT username FROM mailbox WHERE username = '${quote_mysql:$2}' AND password = '${quote_mysql:$3}' AND active = '1'}{value}{*}}
10:13:37 2751 result: *
10:13:37 2751 crypteq: using crypt()
10:13:37 2751 subject=**hdlE8H5jpgs
10:13:37 2751 crypted=*
10:13:37 2751 condition: crypteq{$3}{${lookup mysql{SELECT username FROM mailbox WHERE username = '${quote_mysql:$2}' AND password = '${quote_mysql:$3}' AND active = '1'}{value}{*}}}
10:13:37 2751 result: false
10:13:37 2751 expanding: yes
10:13:37 2751 result: yes
10:13:37 2751 skipping: result is not used
10:13:37 2751 expanding: no
10:13:37 2751 result: no
10:13:37 2751 expanding: ${if crypteq{$3}{${lookup mysql{SELECT username FROM mailbox WHERE username = '${quote_mysql:$2}' AND password = '${quote_mysql:$3}' AND active = '1'}{value}{*}}}{yes}{no}}
10:13:37 2751 result: no
10:13:37 2751 expanded string: no
10:13:37 2751 expanding: $2
10:13:37 2751 result: mail@my-virtualhost.ru
10:13:37 2751 SMTP>> 535 Incorrect authentication data
10:13:37 2751 LOG: MAIN REJECT
10:13:37 2751 auth_plain authenticator failed for ([10.0.8.50]) [193.xxx.xxx.xxx]: 535 Incorrect authentication data (set_id=mail@my-virtualhost.ru)
10:13:37 2751 SMTP<< AUTH LOGIN
10:13:37 2751 SMTP>> 334 VXNlcm5hbWU6
10:13:37 2751 SMTP<< bWFpbEBidWhnYWx0ZXIua3o=
10:13:37 2751 SMTP>> 334 UGFzc3dvcmQ6
10:13:37 2751 SMTP<< cmZlNTYwMg==
10:13:37 2751 auth_login authenticator:
10:13:37 2751 $auth1 = mail@my-virtualhost.ru
10:13:37 2751 $auth2 = password
10:13:37 2751 $1 = mail@my-virtualhost.ru
10:13:37 2751 $2 = password
10:13:37 2751 expanding: $2
10:13:37 2751 result: password
10:13:37 2751 expanding: $1
10:13:37 2751 result: mail@my-virtualhost.ru
10:13:37 2751 expanding: $2
10:13:37 2751 result: password
10:13:37 2751 expanding: SELECT username FROM mailbox WHERE username = '${quote_mysql:$1}' AND password = '${quote_mysql:$2}' AND active = '1'
10:13:37 2751 result: SELECT username FROM mailbox WHERE username = 'mail@my-virtualhost.ru' AND password = 'password' AND active = '1'
10:13:37 2751 search_open: mysql "NULL"
10:13:37 2751 cached open
10:13:37 2751 search_find: file="NULL"
10:13:37 2751 key="SELECT username FROM mailbox WHERE username = 'mail@my-virtualhost.ru' AND password = 'password' AND active = '1'" partial=-1 affix=NULL starflags=0
10:13:37 2751 LRU list:
10:13:37 2751 internal_search_find: file="NULL"
10:13:37 2751 type=mysql key="SELECT username FROM mailbox WHERE username = 'mail@my-virtualhost.ru' AND password = 'password' AND active = '1'"
10:13:37 2751 cached data used for lookup of SELECT username FROM mailbox WHERE username = 'mail@my-virtualhost.ru' AND password = 'password' AND active = '1'
10:13:37 2751 lookup failed
10:13:37 2751 expanding: value
10:13:37 2751 result: value
10:13:37 2751 skipping: result is not used
10:13:37 2751 expanding: *
10:13:37 2751 result: *
10:13:37 2751 expanding: ${lookup mysql{SELECT username FROM mailbox WHERE username = '${quote_mysql:$1}' AND password = '${quote_mysql:$2}' AND active = '1'}{value}{*}}
10:13:37 2751 result: *
10:13:37 2751 crypteq: using crypt()
10:13:37 2751 subject=**hdlE8H5jpgs
10:13:37 2751 crypted=*
10:13:37 2751 condition: crypteq{$2}{${lookup mysql{SELECT username FROM mailbox WHERE username = '${quote_mysql:$1}' AND password = '${quote_mysql:$2}' AND active = '1'}{value}{*}}}
10:13:37 2751 result: false
10:13:37 2751 expanding: yes
10:13:37 2751 result: yes
10:13:37 2751 skipping: result is not used
10:13:37 2751 expanding: no
10:13:37 2751 result: no
10:13:37 2751 expanding: ${if crypteq{$2}{${lookup mysql{SELECT username FROM mailbox WHERE username = '${quote_mysql:$1}' AND password = '${quote_mysql:$2}' AND active = '1'}{value}{*}}}{yes}{no}}
10:13:37 2751 result: no
10:13:37 2751 expanded string: no
10:13:37 2751 expanding: $1
10:13:37 2751 result: mail@my-virtualhost.ru
10:13:37 2751 SMTP>> 535 Incorrect authentication data
10:13:37 2751 LOG: MAIN REJECT
10:13:37 2751 auth_login authenticator failed for ([10.0.8.50]) [193.xxx.xxx.xxx]: 535 Incorrect authentication data (set_id=mail@my-virtualhost.ru)
10:13:39 2751 SMTP<< QUIT
10:13:39 2751 SMTP>> 221 press.kz closing connection
10:13:39 2751 LOG: smtp_connection MAIN
10:13:39 2751 SMTP connection from ([10.0.8.50]) [193.xxx.xxx.xxx] closed by QUIT
10:13:39 2751 search_tidyup called
10:13:39 2751 close MYSQL connection: localhost/exim/exim
10:13:39 2749 child 2751 ended: status=0x0
10:13:39 2749 0 SMTP accept processes now running
10:13:39 2749 Listening...
