Exim съедает заголовки
Добавлено: 2010-06-05 10:53:30
Года три назад настроил Exim в связке с PostgreSQL и SpamAssasin. Принцип работы такой, имеется один корпоративный ящик (на mail.ru), все письма уходят через него (драйвер smtp). Плюс ещё почту во внутреннем домене разруливает. С недавних пор у всех писем уходящих на внешние домены стала обрезаться часть заголовка в начале, в итоге до адресата письма доходят, но в нечитаемом виде. Выглядит примерно так:
Перенастроил отправку через yandex ящик, всё работает. Отключил спамассасин, не помогло. В чём может быть дело?
Лог отправки
Комментарий к логу, всех исходящие письма попадают в архив и вносится запись в базу, также перед отправкой проверяется можно ли юзеру отправлять почту на данный адрес/домен.
Код: Выделить всё
id 1OKSWm-0009aH-00
for address@mail.ru; Fri, 04 Jun 2010 12:45:20 +0400
Received: from [192.168.0.1] (helo=Terminal)
by MTS.afsga.muh with smtp (Exim 4.69 (FreeBSD))
(envelope-from <it@afsga.muh>)
id 1OKSjo-0007D5-N6
for address@mail.ru; Fri, 04 Jun 2010 12:58:48 +0400
Message-ID: <67261BAA10D142BA814F277E92999FC3@afsga.muh>
From: =?koi8-r?B?4dPU0sHIwc7YIPPn4SAtIElU?= <address@mail.ru>
To: =?koi8-r?B?4eYg8+fhIC0gSVQ=?= <address@mail.ru>
Subject: f435ws
Date: Fri, 4 Jun 2010 12:43:30 +0400
MIME-Version: 1.0
Content-Type: multipart/alternative;
boundary="----=_NextPart_000_0044_01CB03E3.891B7A50"
X-Priority: 3
X-MSMail-Priority: Normal
X-Mailer: Microsoft Outlook Express 6.00.3790.4657
X-MimeOLE: Produced By Microsoft MimeOLE V6.00.3790.4657
X-Spam-Checked: NO - AFSGA SPAM FILTER!
X-Mras: Ok
X-Spam-Score: 4.4 (++++)
X-Spam-Scannes: Yes
X-Spam-Scanner: SpamAssassin running on mts.afsga.muh
This is a multi-part message in MIME format.
------=_NextPart_000_0044_01CB03E3.891B7A50
Content-Type: text/plain;
charset="koi8-r"
Content-Transfer-Encoding: quoted-printable
sdfsdgfsdg
__________ NOD32 5173 (20100604) Information __________
This message was checked by NOD32 antivirus system.
http://www.eset.com
------=_NextPart_000_0044_01CB03E3.891B7A50
Content-Type: text/html;
charset="koi8-r"
Content-Transfer-Encoding: quoted-printable
<!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 4.0 Transitional//EN">
<HTML><HEAD>
<META content=3D"text/html; charset=3Dkoi8-r" http-equiv=3DContent-Type>
<META name=3DGENERATOR content=3D"MSHTML 8.00.6001.18904">
<STYLE></STYLE>
</HEAD>
<BODY bgColor=3D#ffffff>
<DIV><FONT size=3D2 face=3DArial>sdfsdgfsdg</FONT></DIV><BR>
<BR>
__________ NOD32 5173 (20100604) Information __________<BR>
<BR>
This message was checked by NOD32 antivirus system.<BR>
<A HREF=3D"http://www.eset.com">http://www.eset.com</A><BR>
</BODY></HTML>
------=_NextPart_000_0044_01CB03E3.891B7A50--
Лог отправки
Код: Выделить всё
exim -bd -d+all
11:46:39 4220 Exim version 4.69 (FreeBSD 6.3) uid=0 gid=0 pid=4220 D=fffdffff
Probably Berkeley DB version 1.8x (native mode)
Support for: crypteq iconv() IPv6 use_setclassresources PAM Perl Expand_dlfunc OpenSSL Content_Scanning Old_Demime
Lookups: lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmnz dnsdb dsearch nis nis0 passwd pgsql
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
11:46:39 4220 changed uid/gid: forcing real = effective
11:46:39 4220 uid=0 gid=0 pid=4220
11:46:39 4220 auxiliary group list: 0
11:46:39 4220 configuration file is /usr/local/etc/exim/configure
11:46:39 4220 log selectors = 00000ffc 00212001
11:46:39 4220 cwd=/usr/local/etc/exim 3 args: exim -bd -d+all
11:46:39 4220 trusted user
11:46:39 4220 admin user
11:46:39 4220 seeking password data for user "mailnull": using cached result
11:46:39 4220 getpwnam() succeeded uid=26 gid=26
11:46:39 4220 originator: uid=0 gid=0 login=root name=Charlie Root
11:46:39 4220 listening on all interfaces (IPv6) port 25
11:46:39 4220 listening on all interfaces (IPv4) port 25
11:46:39 4220 pid written to /var/run/exim.pid
11:46:39 4220 changed uid/gid: running as a daemon
11:46:39 4220 uid=26 gid=6 pid=4220
11:46:39 4220 auxiliary group list: 6 6
11:46:39 4220 LOG: MAIN
11:46:39 4220 exim 4.69 daemon started: pid=4220, no queue runs, listening for SMTP on port 25 (IPv6 and IPv4)
11:46:39 4220 set_process_info: 4220 daemon: no queue runs, listening for SMTP on port 25 (IPv6 and IPv4)
11:46:39 4220 daemon running with uid=26 gid=6 euid=26 egid=6
11:46:39 4220 Listening...
11:46:52 4220 Connection request from 192.168.0.14 port 3042
11:46:52 4220 interface address=192.168.0.9 port=25
11:46:52 4220 search_tidyup called
11:46:52 4220 1 SMTP accept process running
11:46:52 4220 Listening...
11:46:52 4221 host in rfc1413_hosts? yes (matched "*")
11:46:52 4221 doing ident callback
11:46:52 4221 ident connection to 192.168.0.14 failed: Connection refused
11:46:52 4221 sender_fullhost = [192.168.0.14]
11:46:52 4221 sender_rcvhost = [192.168.0.14]
11:46:52 4221 Process 4221 is handling incoming connection from [192.168.0.14]
11:46:52 4221 host in host_lookup? no (option unset)
11:46:52 4221 set_process_info: 4221 handling incoming connection from [192.168.0.14]
11:46:52 4221 host in host_reject_connection? no (option unset)
11:46:52 4221 host in sender_unqualified_hosts? no (option unset)
11:46:52 4221 host in recipient_unqualified_hosts? no (option unset)
11:46:52 4221 host in helo_verify_hosts? no (option unset)
11:46:52 4221 host in helo_try_verify_hosts? no (option unset)
11:46:52 4221 host in helo_accept_junk_hosts? no (option unset)
11:46:52 4221 expanding: $smtp_active_hostname ESMTP Exim $version_number $tod_full
11:46:52 4221 result: MTS.afsga.muh ESMTP Exim 4.69 Sat, 05 Jun 2010 11:46:52 +0400
11:46:52 4221 SMTP>> 220 MTS.afsga.muh ESMTP Exim 4.69 Sat, 05 Jun 2010 11:46:52 +0400
11:46:52 4221 Process 4221 is ready for new message
11:46:52 4221 smtp_setup_msg entered
11:46:52 4221 SMTP<< EHLO homepc
11:46:52 4221 homepc in helo_lookup_domains? no (end of list)
11:46:52 4221 sender_fullhost = (homepc) [192.168.0.14]
11:46:52 4221 sender_rcvhost = [192.168.0.14] (helo=homepc)
11:46:52 4221 set_process_info: 4221 handling incoming connection from (homepc) [192.168.0.14]
11:46:52 4221 host in pipelining_advertise_hosts? yes (matched "*")
11:46:52 4221 host in auth_advertise_hosts? yes (matched "*")
11:46:52 4221 host in tls_advertise_hosts? no (option unset)
11:46:52 4221 SMTP>> 250-MTS.afsga.muh Hello homepc [192.168.0.14]
11:46:52 4221 250-SIZE 52428800
11:46:52 4221 250-PIPELINING
11:46:52 4221 250 HELP
11:46:52 4221 SMTP<< MAIL FROM: <admin@afsga.muh>
11:46:52 4221 using ACL "acl_mail_from"
11:46:52 4221 processing "accept"
11:46:52 4221 expanding: $sender_address
11:46:52 4221 result: admin@afsga.muh
11:46:52 4221 expanding:
11:46:52 4221 result:
11:46:52 4221 condition: !eq{$sender_address}{}
11:46:52 4221 result: true
11:46:52 4221 expanding: ${if !eq{$sender_address}{} }
11:46:52 4221 result: true
11:46:52 4221 check condition = ${if !eq{$sender_address}{} }
11:46:52 4221 = true
11:46:52 4221 accept: condition test succeeded
11:46:52 4221 SMTP>> 250 OK
11:46:52 4221 SMTP<< RCPT TO: <address@yandex.ru>
11:46:52 4221 using ACL "my_recive_acl"
11:46:52 4221 processing "accept"
11:46:52 4221 check domains = +my_local_domains
11:46:52 4221 yandex.ru in "afsga.muh : *.afsga.muh"? no (end of list)
11:46:52 4221 yandex.ru in "+my_local_domains"? no (end of list)
11:46:52 4221 accept: condition test failed
11:46:52 4221 processing "accept"
11:46:52 4221 expanding: SELECT 1 FROM allowaddress a RIGHT OUTER JOIN accounts acc ON a.uid=acc.uid WHERE (acc.login='$sender_address') and (a.address ilike '$local_part@$domain' or a.address ilike '$domain' or acc.fullaccess=TRUE)
11:46:52 4221 result: SELECT 1 FROM allowaddress a RIGHT OUTER JOIN accounts acc ON a.uid=acc.uid WHERE (acc.login='admin@afsga.muh') and (a.address ilike 'address@yandex.ru' or a.address ilike 'yandex.ru' or acc.fullaccess=TRUE)
11:46:52 4221 search_open: pgsql "NULL"
11:46:52 4221 search_find: file="NULL"
11:46:52 4221 key="SELECT 1 FROM allowaddress a RIGHT OUTER JOIN accounts acc ON a.uid=acc.uid WHERE (acc.login='admin@afsga.muh') and (a.address ilike 'address@yandex.ru' or a.address ilike 'yandex.ru' or acc.fullaccess=TRUE)" partial=-1 affix=NULL starflags=0
11:46:52 4221 LRU list:
11:46:52 4221 internal_search_find: file="NULL"
11:46:52 4221 type=pgsql key="SELECT 1 FROM allowaddress a RIGHT OUTER JOIN accounts acc ON a.uid=acc.uid WHERE (acc.login='admin@afsga.muh') and (a.address ilike 'address@yandex.ru' or a.address ilike 'yandex.ru' or acc.fullaccess=TRUE)"
11:46:52 4221 database lookup required for SELECT 1 FROM allowaddress a RIGHT OUTER JOIN accounts acc ON a.uid=acc.uid WHERE (acc.login='admin@afsga.muh') and (a.address ilike 'address@yandex.ru' or a.address ilike 'yandex.ru' or acc.fullaccess=TRUE)
11:46:52 4221 PostgreSQL query: SELECT 1 FROM allowaddress a RIGHT OUTER JOIN accounts acc ON a.uid=acc.uid WHERE (acc.login='admin@afsga.muh') and (a.address ilike 'address@yandex.ru' or a.address ilike 'yandex.ru' or acc.fullaccess=TRUE)
11:46:52 4221 PGSQL new connection: socket=/tmp/.s.PGSQL.5432 database=users user=pgsql
11:46:52 4221 lookup yielded: 1
11:46:52 4221 expanding: yes
11:46:52 4221 result: yes
11:46:52 4221 expanding: no
11:46:52 4221 result: no
11:46:52 4221 skipping: result is not used
11:46:52 4221 expanding: ${lookup pgsql{SELECT 1 FROM allowaddress a RIGHT OUTER JOIN accounts acc ON a.uid=acc.uid WHERE (acc.login='$sender_address') and (a.address ilike '$local_part@$domain' or a.address ilike '$domain' or acc.fullaccess=TRUE)}{yes}{no}}
11:46:52 4221 result: yes
11:46:52 4221 check condition = ${lookup pgsql{SELECT 1 FROM allowaddress a RIGHT OUTER JOIN accounts acc ON a.uid=acc.uid WHERE (acc.login='$sender_address') and (a.address ilike '$local_part@$domain' or a.address ilike '$domain' or acc.fullaccess=TRUE)}{yes}{no}}
11:46:52 4221 = yes
11:46:52 4221 expanding: ${acl_c1}${local_part}@$domain\;
11:46:52 4221 result: address@yandex.ru;
11:46:52 4221 check set acl_c1 = ${acl_c1}${local_part}@$domain\;
11:46:52 4221 = address@yandex.ru;
11:46:52 4221 accept: condition test succeeded
11:46:52 4221 SMTP>> 250 Accepted
11:46:52 4221 SMTP<< DATA
11:46:52 4221 SMTP>> 354 Enter message, ending with "." on a line by itself
11:46:52 4221 search_tidyup called
11:46:52 4221 close PGSQL connection: (/tmp/.s.PGSQL.5432)/users/pgsql
11:46:52 4221 host in ignore_fromline_hosts? no (option unset)
11:46:52 4221 >>Headers received:
11:46:52 4221 From: "Astrakhan SGA #1" <admin@afsga.muh>
11:46:52 4221 To: <address@yandex.ru>
11:46:52 4221 Subject: asfasfasfasfasfasdf
11:46:52 4221 Date: Sat, 5 Jun 2010 11:31:47 +0400
11:46:52 4221 MIME-Version: 1.0
11:46:52 4221 Content-Type: multipart/alternative;
11:46:52 4221 boundary="----=_NextPart_000_0009_01CB04A2.AEFADBC0"
11:46:52 4221 X-Mailer: Microsoft Office Outlook, Build 11.0.5510
11:46:52 4221 Thread-Index: AcsEgSfT8a3cQZAFSNO4kxGU2QvaIw==
11:46:52 4221 X-MimeOLE: Produced By Microsoft MimeOLE V6.00.2900.5512
11:46:52 4221
11:46:52 4221 rewrite_one_header: type=F:
11:46:52 4221 From: "Astrakhan SGA #1" <admin@afsga.muh>
11:46:52 4221 rewrite_one_header: type=T:
11:46:52 4221 To: <address@yandex.ru>
11:46:52 4221 search_tidyup called
11:46:52 4221 >>Headers after rewriting and local additions:
11:46:52 4221 F From: "Astrakhan SGA #1" <admin@afsga.muh>
11:46:52 4221 T To: <address@yandex.ru>
11:46:52 4221 Subject: asfasfasfasfasfasdf
11:46:52 4221 Date: Sat, 5 Jun 2010 11:31:47 +0400
11:46:52 4221 MIME-Version: 1.0
11:46:52 4221 Content-Type: multipart/alternative;
11:46:52 4221 boundary="----=_NextPart_000_0009_01CB04A2.AEFADBC0"
11:46:52 4221 X-Mailer: Microsoft Office Outlook, Build 11.0.5510
11:46:52 4221 Thread-Index: AcsEgSfT8a3cQZAFSNO4kxGU2QvaIw==
11:46:52 4221 X-MimeOLE: Produced By Microsoft MimeOLE V6.00.2900.5512
11:46:52 4221
11:46:52 4221 Data file written for message 1OKo5k-000165-H6
11:46:52 4221 expanding: ${tod_full}
11:46:52 4221 result: Sat, 05 Jun 2010 11:46:52 +0400
11:46:52 4221 condition: def:sender_rcvhost
11:46:52 4221 result: true
11:46:52 4221 expanding: from $sender_rcvhost
11:46:52 4221
11:46:52 4221 result: from [192.168.0.14] (helo=homepc)
11:46:52 4221
11:46:52 4221 condition: def:sender_ident
11:46:52 4221 result: false
11:46:52 4221 expanding: $sender_ident
11:46:52 4221 result:
11:46:52 4221 skipping: result is not used
11:46:52 4221 expanding: from ${quote_local_part:$sender_ident}
11:46:52 4221 result: from
11:46:52 4221 skipping: result is not used
11:46:52 4221 condition: def:sender_helo_name
11:46:52 4221 result: false
11:46:52 4221 expanding: (helo=$sender_helo_name)
11:46:52 4221
11:46:52 4221 result: (helo=)
11:46:52 4221
11:46:52 4221 skipping: result is not used
11:46:52 4221 expanding: ${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
11:46:52 4221 }}
11:46:52 4221 result:
11:46:52 4221 skipping: result is not used
11:46:52 4221 condition: def:received_protocol
11:46:52 4221 result: true
11:46:52 4221 expanding: with $received_protocol
11:46:52 4221 result: with esmtp
11:46:52 4221 condition: def:tls_cipher
11:46:52 4221 result: false
11:46:52 4221 expanding: ($tls_cipher)
11:46:52 4221
11:46:52 4221 result: ()
11:46:52 4221
11:46:52 4221 skipping: result is not used
11:46:52 4221 condition: def:sender_address
11:46:52 4221 result: true
11:46:52 4221 expanding: (envelope-from <$sender_address>)
11:46:52 4221
11:46:52 4221 result: (envelope-from <admin@afsga.muh>)
11:46:52 4221
11:46:52 4221 condition: def:received_for
11:46:52 4221 result: true
11:46:52 4221 expanding:
11:46:52 4221 for $received_for
11:46:52 4221 result:
11:46:52 4221 for address@yandex.ru
11:46:52 4221 expanding: Received: ${if def:sender_rcvhost {from $sender_rcvhost
11:46:52 4221 }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
11:46:52 4221 }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol}} ${if def:tls_cipher {($tls_cipher)
11:46:52 4221 }}(Exim $version_number (FreeBSD))
11:46:52 4221 ${if def:sender_address {(envelope-from <$sender_address>)
11:46:52 4221 }}id $message_exim_id${if def:received_for {
11:46:52 4221 for $received_for}}
11:46:52 4221 result: Received: from [192.168.0.14] (helo=homepc)
11:46:52 4221 by MTS.afsga.muh with esmtp (Exim 4.69 (FreeBSD))
11:46:52 4221 (envelope-from <admin@afsga.muh>)
11:46:52 4221 id 1OKo5k-000165-H6
11:46:52 4221 for address@yandex.ru
11:46:52 4221 >>Generated Received: header line
11:46:52 4221 P Received: from [192.168.0.14] (helo=homepc)
11:46:52 4221 by MTS.afsga.muh with esmtp (Exim 4.69 (FreeBSD))
11:46:52 4221 (envelope-from <admin@afsga.muh>)
11:46:52 4221 id 1OKo5k-000165-H6
11:46:52 4221 for address@yandex.ru; Sat, 05 Jun 2010 11:46:52 +0400
11:46:52 4221 using ACL "acl_check_data"
11:46:52 4221 processing "warn"
11:46:52 4221 expanding: $h_message-id:
11:46:52 4221 result:
11:46:52 4221 expanding:
11:46:52 4221 result:
11:46:52 4221 condition: !eq{$h_message-id:}{}
11:46:52 4221 result: false
11:46:52 4221 expanding: ${if !eq{$h_message-id:}{}}
11:46:52 4221 result:
11:46:52 4221 check condition = ${if !eq{$h_message-id:}{}}
11:46:52 4221 =
11:46:52 4221 warn: condition test failed
11:46:52 4221 processing "accept"
11:46:52 4221 expanding: $message_size
11:46:52 4221 result: 4058
11:46:52 4221 expanding: 200k
11:46:52 4221 result: 200k
11:46:52 4221 expanding: $sender_address
11:46:52 4221 result: admin@afsga.muh
11:46:52 4221 expanding: .@afsga.muh
11:46:52 4221 result: .@afsga.muh
11:46:52 4221 condition: or{{>{$message_size}{200k}}{match{$sender_address}{.@afsga.muh}}}
11:46:52 4221 result: true
11:46:52 4221 expanding: ${if or{{>{$message_size}{200k}}{match{$sender_address}{.@afsga.muh}}}}
11:46:52 4221 result: true
11:46:52 4221 check condition = ${if or{{>{$message_size}{200k}}{match{$sender_address}{.@afsga.muh}}}}
11:46:52 4221 = true
11:46:52 4221 check add_header = X-Spam-Checked: NO - AFSGA SPAM FILTER!
11:46:52 4221 accept: condition test succeeded
11:46:52 4221 >>Headers added by DATA ACL:
11:46:52 4221 X-Spam-Checked: NO - AFSGA SPAM FILTER!
11:46:52 4221 >>
11:46:52 4221 calling local_scan(); timeout=300
11:46:52 4221 local_scan() returned 0 NULL
11:46:52 4221 expanding: ${tod_full}
11:46:52 4221 result: Sat, 05 Jun 2010 11:46:52 +0400
11:46:52 4221 Writing spool header file
11:46:52 4221 Size of headers = 645
11:46:52 4221 LOG: MAIN
11:46:52 4221 <= admin@afsga.muh H=(homepc) [192.168.0.14] P=esmtp S=4305
11:46:52 4221 SMTP>> 250 OK id=1OKo5k-000165-H6
11:46:52 4221 search_tidyup called
11:46:52 4221 Sender: admin@afsga.muh
11:46:52 4221 Recipients:
11:46:52 4221 address@yandex.ru
11:46:52 4221 forked delivery process 4223
11:46:52 4223 exec /usr/local/sbin/exim -d=0xfffdffff -Mc 1OKo5k-000165-H6
11:46:52 4221 Process 4221 is ready for new message
11:46:52 4223 Exim version 4.69 (FreeBSD 6.3) uid=26 gid=6 pid=4223 D=fffdffff
Probably Berkeley DB version 1.8x (native mode)
Support for: crypteq iconv() IPv6 use_setclassresources PAM Perl Expand_dlfunc OpenSSL Content_Scanning Old_Demime
Lookups: lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmnz dnsdb dsearch nis nis0 passwd pgsql
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
11:46:52 4223 changed uid/gid: forcing real = effective
11:46:52 4223 uid=0 gid=6 pid=4223
11:46:52 4223 auxiliary group list: 6
11:46:52 4223 configuration file is /usr/local/etc/exim/configure
11:46:52 4223 log selectors = 00000ffc 00212001
11:46:52 4223 cwd=/var/spool/exim 4 args: /usr/local/sbin/exim -d=0xfffdffff -Mc 1OKo5k-000165-H6
11:46:52 4223 trusted user
11:46:52 4223 admin user
11:46:52 4223 skipping ACL configuration - not needed
11:46:52 4223 seeking password data for user "mailnull": using cached result
11:46:52 4223 getpwnam() succeeded uid=26 gid=26
11:46:52 4223 set_process_info: 4223 delivering specified messages
11:46:52 4223 set_process_info: 4223 delivering 1OKo5k-000165-H6
11:46:52 4223 reading spool file 1OKo5k-000165-H6-H
11:46:52 4223 user=mailnull uid=26 gid=6 sender=admin@afsga.muh
11:46:52 4223 sender_fullhost = (homepc) [192.168.0.14]
11:46:52 4223 sender_rcvhost = [192.168.0.14] (helo=homepc)
11:46:52 4223 sender_local=0 ident=unset
11:46:52 4223 Non-recipients:
11:46:52 4223 Empty Tree
11:46:52 4223 ---- End of tree ----
11:46:52 4223 recipients_count=1
11:46:52 4223 body_linecount=118 message_linecount=16
11:46:52 4223 Delivery address list:
11:46:52 4223 address@yandex.ru
11:46:52 4223 locking /var/spool/exim/db/retry.lockfile
11:46:52 4223 locked /var/spool/exim/db/retry.lockfile
11:46:52 4223 EXIM_DBOPEN(/var/spool/exim/db/retry)
11:46:52 4221 smtp_setup_msg entered
11:46:52 4223 returned from EXIM_DBOPEN
11:46:52 4223 opened hints database /var/spool/exim/db/retry: flags=O_RDONLY
11:46:52 4223 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
11:46:52 4223 Considering: address@yandex.ru
11:46:52 4223 unique = address@yandex.ru
11:46:52 4223 dbfn_read: key=R:yandex.ru
11:46:52 4223 dbfn_read: key=R:address@yandex.ru
11:46:52 4223 dbfn_read: key=R:address@yandex.ru:<admin@afsga.muh>
11:46:52 4223 no domain retry record
11:46:52 4223 no address retry record
11:46:52 4223 address@yandex.ru: queued for routing
11:46:52 4223 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
11:46:52 4223 routing address@yandex.ru
11:46:52 4223 --------> archive_out router <--------
11:46:52 4223 local_part=tnzheka domain=yandex.ru
11:46:52 4223 checking domains
11:46:52 4223 yandex.ru in "afsga.muh : *.afsga.muh"? no (end of list)
11:46:52 4223 yandex.ru in "!+my_local_domains"? yes (end of list)
11:46:52 4223 calling archive_out router
11:46:52 4223 rda_interpret (string): archive-out@afsga.muh
11:46:52 4223 expanded: archive-out@afsga.muh
11:46:52 4223 file is not a filter file
11:46:52 4223 parse_forward_list: archive-out@afsga.muh
11:46:52 4223 extract item: archive-out@afsga.muh
11:46:52 4223 archive_out router generated archive-out@afsga.muh
11:46:52 4223 errors_to=NULL transport=NULL
11:46:52 4223 uid=unset gid=unset home=NULL
11:46:52 4223 routed by archive_out router (unseen)
11:46:52 4223 envelope to: address@yandex.ru
11:46:52 4223 transport: <none>
11:46:52 4223 "unseen" set: replicated address@yandex.ru
11:46:52 4223 locking /var/spool/exim/db/retry.lockfile
11:46:52 4223 locked /var/spool/exim/db/retry.lockfile
11:46:52 4223 EXIM_DBOPEN(/var/spool/exim/db/retry)
11:46:52 4223 returned from EXIM_DBOPEN
11:46:52 4223 opened hints database /var/spool/exim/db/retry: flags=O_RDONLY
11:46:52 4223 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
11:46:52 4223 Considering: address@yandex.ru
11:46:52 4223 unique = \0\address@yandex.ru
11:46:52 4223 dbfn_read: key=R:yandex.ru
11:46:52 4223 dbfn_read: key=R:address@yandex.ru
11:46:52 4223 dbfn_read: key=R:address@yandex.ru:<admin@afsga.muh>
11:46:52 4223 no domain retry record
11:46:52 4223 no address retry record
11:46:52 4223 address@yandex.ru: queued for routing
11:46:52 4223 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
11:46:52 4223 Considering: archive-out@afsga.muh
11:46:52 4223 unique = archive-out@afsga.muh
11:46:52 4223 dbfn_read: key=R:afsga.muh
11:46:52 4223 dbfn_read: key=R:archive-out@afsga.muh
11:46:52 4223 dbfn_read: key=R:archive-out@afsga.muh:<admin@afsga.muh>
11:46:52 4223 no domain retry record
11:46:52 4223 no address retry record
11:46:52 4223 archive-out@afsga.muh: queued for routing
11:46:52 4223 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
11:46:52 4223 routing archive-out@afsga.muh
11:46:52 4223 --------> archive_out router <--------
11:46:52 4223 local_part=archive-out domain=afsga.muh
11:46:52 4223 checking domains
11:46:52 4223 afsga.muh in "afsga.muh : *.afsga.muh"? yes (matched "afsga.muh")
11:46:52 4223 afsga.muh in "!+my_local_domains"? no (matched "!+my_local_domains")
11:46:52 4223 archive_out router skipped: domains mismatch
11:46:52 4223 --------> archive_dsn_in router <--------
11:46:52 4223 local_part=archive-out domain=afsga.muh
11:46:52 4223 checking local_parts
11:46:52 4223 archive-out in "!archive-dsn-in"? yes (end of list)
11:46:52 4223 checking "condition"
11:46:52 4223 expanding: $acl_c3
11:46:52 4223 result:
11:46:52 4223 empty string cast to zero for numerical comparison
11:46:52 4223 expanding: 0
11:46:52 4223 result: 0
11:46:52 4223 condition: >{$acl_c3}{0}
11:46:52 4223 result: false
11:46:52 4223 expanding: ${if >{$acl_c3}{0}}
11:46:52 4223 result:
11:46:52 4223 archive_dsn_in router skipped: condition failure
11:46:52 4223 --------> send_to_mail_muh_ru router <--------
11:46:52 4223 local_part=archive-out domain=afsga.muh
11:46:52 4223 checking domains
11:46:52 4223 afsga.muh in "muh.ru : campus.muh.ru : sgutv.ru"? no (end of list)
11:46:52 4223 afsga.muh in "+domain_muh"? no (end of list)
11:46:52 4223 send_to_mail_muh_ru router skipped: domains mismatch
11:46:52 4223 --------> send_to_gate_muh_ru router <--------
11:46:52 4223 local_part=archive-out domain=afsga.muh
11:46:52 4223 checking domains
11:46:52 4223 afsga.muh in "filial.sga"? no (end of list)
11:46:52 4223 send_to_gate_muh_ru router skipped: domains mismatch
11:46:52 4223 --------> send_to_gateway router <--------
11:46:52 4223 local_part=archive-out domain=afsga.muh
11:46:52 4223 checking domains
11:46:52 4223 cached yes match for +my_local_domains
11:46:52 4223 cached lookup data = NULL
11:46:52 4223 afsga.muh in "!+my_local_domains"? no (matched "!+my_local_domains" - cached)
11:46:52 4223 send_to_gateway router skipped: domains mismatch
11:46:52 4223 --------> from_inet_to_procmail router <--------
11:46:52 4223 local_part=archive-out domain=afsga.muh
11:46:52 4223 checking local_parts
11:46:52 4223 archive-out in "!archive-dsn-in"? yes (end of list)
11:46:52 4223 checking "condition"
11:46:52 4223 expanding: $sender_address
11:46:52 4223 result: admin@afsga.muh
11:46:52 4223 expanding: .@afsga.muh
11:46:52 4223 result: .@afsga.muh
11:46:52 4223 expanding: $sender_address
11:46:52 4223 result:
11:46:52 4223 skipping: result is not used
11:46:52 4223 expanding:
11:46:52 4223 result:
11:46:52 4223 skipping: result is not used
11:46:52 4223 condition: and{ {!match{$sender_address}{.@afsga.muh}}{!eq{$sender_address}{}}}
11:46:52 4223 result: false
11:46:52 4223 expanding: 1
11:46:52 4223 result: 1
11:46:52 4223 skipping: result is not used
11:46:52 4223 expanding: 0
11:46:52 4223 result: 0
11:46:52 4223 expanding: ${if and{ {!match{$sender_address}{.@afsga.muh}}{!eq{$sender_address}{}}} {1} {0} }
11:46:52 4223 result: 0
11:46:52 4223 from_inet_to_procmail router skipped: condition failure
11:46:52 4223 --------> my_local_domains_router router <--------
11:46:52 4223 local_part=archive-out domain=afsga.muh
11:46:52 4223 checking "condition"
11:46:52 4223 expanding: select uid from accounts where login = '$local_part@$domain'
11:46:52 4223 result: select uid from accounts where login = 'archive-out@afsga.muh'
11:46:52 4223 search_open: pgsql "NULL"
11:46:52 4223 search_find: file="NULL"
11:46:52 4223 key="select uid from accounts where login = 'archive-out@afsga.muh'" partial=-1 affix=NULL starflags=0
11:46:52 4223 LRU list:
11:46:52 4223 internal_search_find: file="NULL"
11:46:52 4223 type=pgsql key="select uid from accounts where login = 'archive-out@afsga.muh'"
11:46:52 4223 database lookup required for select uid from accounts where login = 'archive-out@afsga.muh'
11:46:52 4223 PostgreSQL query: select uid from accounts where login = 'archive-out@afsga.muh'
11:46:52 4223 PGSQL new connection: socket=/tmp/.s.PGSQL.5432 database=users user=pgsql
11:46:52 4223 lookup yielded: 18
11:46:52 4223 expanding: yes
11:46:52 4223 result: yes
11:46:52 4223 expanding: no
11:46:52 4223 result: no
11:46:52 4223 skipping: result is not used
11:46:52 4223 expanding: ${lookup pgsql{select uid from accounts where login = '$local_part@$domain'}{yes}{no}}
11:46:52 4223 result: yes
11:46:52 4223 calling my_local_domains_router router
11:46:52 4223 my_local_domains_router router called for archive-out@afsga.muh
11:46:52 4223 domain = afsga.muh
11:46:52 4223 set transport my_local_delivery_transport
11:46:52 4223 queued for my_local_delivery_transport transport: local_part = archive-out
11:46:52 4223 domain = afsga.muh
11:46:52 4223 errors_to=NULL
11:46:52 4223 domain_data=NULL localpart_data=NULL
11:46:52 4223 routed by my_local_domains_router router
11:46:52 4223 envelope to: archive-out@afsga.muh
11:46:52 4223 transport: my_local_delivery_transport
11:46:52 4223 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
11:46:52 4223 routing address@yandex.ru
11:46:52 4223 --------> archive_dsn_in router <--------
11:46:52 4223 local_part=tnzheka domain=yandex.ru
11:46:52 4223 checking local_parts
11:46:52 4223 tnzheka in "!archive-dsn-in"? yes (end of list)
11:46:52 4223 checking "condition"
11:46:52 4223 expanding: $acl_c3
11:46:52 4223 result:
11:46:52 4223 empty string cast to zero for numerical comparison
11:46:52 4223 expanding: 0
11:46:52 4223 result: 0
11:46:52 4223 condition: >{$acl_c3}{0}
11:46:52 4223 result: false
11:46:52 4223 expanding: ${if >{$acl_c3}{0}}
11:46:52 4223 result:
11:46:52 4223 archive_dsn_in router skipped: condition failure
11:46:52 4223 --------> send_to_mail_muh_ru router <--------
11:46:52 4223 local_part=tnzheka domain=yandex.ru
11:46:52 4223 checking domains
11:46:52 4223 yandex.ru in "muh.ru : campus.muh.ru : sgutv.ru"? no (end of list)
11:46:52 4223 yandex.ru in "+domain_muh"? no (end of list)
11:46:52 4223 send_to_mail_muh_ru router skipped: domains mismatch
11:46:52 4223 --------> send_to_gate_muh_ru router <--------
11:46:52 4223 local_part=tnzheka domain=yandex.ru
11:46:52 4223 checking domains
11:46:52 4223 yandex.ru in "filial.sga"? no (end of list)
11:46:52 4223 send_to_gate_muh_ru router skipped: domains mismatch
11:46:52 4223 --------> send_to_gateway router <--------
11:46:52 4223 local_part=tnzheka domain=yandex.ru
11:46:52 4223 checking domains
11:46:52 4223 yandex.ru in "afsga.muh : *.afsga.muh"? no (end of list)
11:46:52 4223 yandex.ru in "!+my_local_domains"? yes (end of list)
11:46:52 4223 calling send_to_gateway router
11:46:52 4223 send_to_gateway router called for address@yandex.ru
11:46:52 4223 domain = yandex.ru
11:46:52 4223 route_item = * smtp.yandex.ru
11:46:52 4223 yandex.ru in "*"? yes (matched "*")
11:46:52 4223 original list of hosts = "smtp.yandex.ru" options =
11:46:52 4223 expanded list of hosts = "smtp.yandex.ru" options =
11:46:52 4223 set transport remote_smtp
11:46:52 4223 finding IP address for smtp.yandex.ru
11:46:52 4223 doing DNS lookup
;; res_nquerydomain(smtp.yandex.ru, <Nil>, 1, 28)
;; res_query(smtp.yandex.ru, 1, 28)
;; res_nmkquery(QUERY, smtp.yandex.ru, IN, AAAA)
;; res_send()
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 13596
;; flags: rd; QUERY: 1, ANSWER: 0, AUTHORITY: 0, ADDITIONAL: 0
;; smtp.yandex.ru, type = AAAA, class = IN
;; Querying server (# 1) address = 192.168.0.11
;; new DG socket
;; got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 13596
;; flags: qr rd ra; QUERY: 1, ANSWER: 0, AUTHORITY: 1, ADDITIONAL: 0
;; smtp.yandex.ru, type = AAAA, class = IN
yandex.ru. 14m2s IN SOA ns1.yandex.ru. sysadmin.yandex-team.ru. (
2010060411 ; serial
30M ; refresh
15M ; retry
4w2d ; expiry
15M ) ; minimum
;; rcode = (NOERROR), counts = an:0 ns:1 ar:0
11:46:52 4223 DNS lookup of smtp.yandex.ru (AAAA) gave NO_DATA
11:46:52 4223 returning DNS_NODATA
;; res_nquerydomain(smtp.yandex.ru, <Nil>, 1, 1)
;; res_query(smtp.yandex.ru, 1, 1)
;; res_nmkquery(QUERY, smtp.yandex.ru, IN, A)
;; res_send()
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 13597
;; flags: rd; QUERY: 1, ANSWER: 0, AUTHORITY: 0, ADDITIONAL: 0
;; smtp.yandex.ru, type = A, class = IN
;; Querying server (# 1) address = 192.168.0.11
;; new DG socket
;; got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 13597
;; flags: qr rd ra; QUERY: 1, ANSWER: 3, AUTHORITY: 0, ADDITIONAL: 0
;; smtp.yandex.ru, type = A, class = IN
smtp.yandex.ru. 3m2s IN A 77.88.21.38
smtp.yandex.ru. 3m2s IN A 93.158.134.38
smtp.yandex.ru. 3m2s IN A 213.180.204.38
11:46:52 4223 DNS lookup of smtp.yandex.ru (A) succeeded
11:46:52 4223 77.88.21.38 in "0.0.0.0 : 127.0.0.0.8"? no (malformed IPv4 address or address mask)
11:46:52 4223 93.158.134.38 in "0.0.0.0 : 127.0.0.0.8"? no (malformed IPv4 address or address mask)
11:46:52 4223 213.180.204.38 in "0.0.0.0 : 127.0.0.0.8"? no (malformed IPv4 address or address mask)
11:46:52 4223 Actual local interface address is 192.168.0.9 (xl0)
11:46:52 4223 Actual local interface address is 10.30.30.9 (xl0)
11:46:52 4223 Actual local interface address is 192.168.50.9 (xl0)
11:46:52 4223 Actual local interface address is 91.211.142.18 (rl0)
11:46:52 4223 Actual local interface address is fe80:4::1 (lo0)
11:46:52 4223 Actual local interface address is ::1 (lo0)
11:46:52 4223 Actual local interface address is 127.0.0.1 (lo0)
11:46:52 4223 fully qualified name = smtp.yandex.ru
11:46:52 4223 smtp.yandex.ru 77.88.21.38 mx=-1 sort=-329
11:46:52 4223 smtp.yandex.ru 93.158.134.38 mx=-1 sort=-194
11:46:52 4223 smtp.yandex.ru 213.180.204.38 mx=-1 sort=-169
11:46:52 4223 queued for remote_smtp transport: local_part = tnzheka
11:46:52 4223 domain = yandex.ru
11:46:52 4223 errors_to=NULL
11:46:52 4223 domain_data=NULL localpart_data=NULL
11:46:52 4223 routed by send_to_gateway router
11:46:52 4223 envelope to: address@yandex.ru
11:46:52 4223 transport: remote_smtp
11:46:52 4223 host smtp.yandex.ru [77.88.21.38]
11:46:52 4223 host smtp.yandex.ru [93.158.134.38]
11:46:52 4223 host smtp.yandex.ru [213.180.204.38]
11:46:52 4223 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
11:46:52 4223 After routing:
11:46:52 4223 Local deliveries:
11:46:52 4223 archive-out@afsga.muh
11:46:52 4223 Remote deliveries:
11:46:52 4223 address@yandex.ru
11:46:52 4223 Failed addresses:
11:46:52 4223 Deferred addresses:
11:46:52 4223 search_tidyup called
11:46:52 4223 close PGSQL connection: (/tmp/.s.PGSQL.5432)/users/pgsql
11:46:52 4223 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
11:46:52 4223 --------> archive-out@afsga.muh <--------
11:46:52 4223 locking /var/spool/exim/db/retry.lockfile
11:46:52 4223 locked /var/spool/exim/db/retry.lockfile
11:46:52 4223 EXIM_DBOPEN(/var/spool/exim/db/retry)
11:46:52 4223 returned from EXIM_DBOPEN
11:46:52 4223 opened hints database /var/spool/exim/db/retry: flags=O_RDONLY
11:46:52 4223 dbfn_read: key=T:archive-out@afsga.muh
11:46:52 4223 no retry record exists
11:46:52 4223 expanding: select gid from accounts where login = '$local_part@$domain'
11:46:52 4223 result: select gid from accounts where login = 'archive-out@afsga.muh'
11:46:52 4223 search_open: pgsql "NULL"
11:46:52 4223 search_find: file="NULL"
11:46:52 4223 key="select gid from accounts where login = 'archive-out@afsga.muh'" partial=-1 affix=NULL starflags=0
11:46:52 4223 LRU list:
11:46:52 4223 internal_search_find: file="NULL"
11:46:52 4223 type=pgsql key="select gid from accounts where login = 'archive-out@afsga.muh'"
11:46:52 4223 database lookup required for select gid from accounts where login = 'archive-out@afsga.muh'
11:46:52 4223 PostgreSQL query: select gid from accounts where login = 'archive-out@afsga.muh'
11:46:52 4223 PGSQL new connection: socket=/tmp/.s.PGSQL.5432 database=users user=pgsql
11:46:52 4223 lookup yielded: 6
11:46:52 4223 expanding: $value
11:46:52 4223 result: 6
11:46:52 4223 expanding: ${lookup pgsql{select gid from accounts where login = '$local_part@$domain'}{$value}fail}
11:46:52 4223 result: 6
11:46:52 4223 expanding: select uid from accounts where login = '$local_part@$domain'
11:46:52 4223 result: select uid from accounts where login = 'archive-out@afsga.muh'
11:46:52 4223 search_open: pgsql "NULL"
11:46:52 4223 cached open
11:46:52 4223 search_find: file="NULL"
11:46:52 4223 key="select uid from accounts where login = 'archive-out@afsga.muh'" partial=-1 affix=NULL starflags=0
11:46:52 4223 LRU list:
11:46:52 4223 internal_search_find: file="NULL"
11:46:52 4223 type=pgsql key="select uid from accounts where login = 'archive-out@afsga.muh'"
11:46:52 4223 database lookup required for select uid from accounts where login = 'archive-out@afsga.muh'
11:46:52 4223 PostgreSQL query: select uid from accounts where login = 'archive-out@afsga.muh'
11:46:52 4223 PGSQL using cached connection for (/tmp/.s.PGSQL.5432)/users/pgsql
11:46:52 4223 lookup yielded: 18
11:46:52 4223 expanding: $value
11:46:52 4223 result: 18
11:46:52 4223 expanding: ${lookup pgsql{select uid from accounts where login = '$local_part@$domain'}{$value}fail}
11:46:52 4223 result: 18
11:46:52 4223 seeking password data for user "18": cache not available
11:46:52 4223 search_tidyup called
11:46:52 4223 close PGSQL connection: (/tmp/.s.PGSQL.5432)/users/pgsql
11:46:52 4226 expanding: select mailquota from accounts where login = '$local_part@$domain'
11:46:52 4226 result: select mailquota from accounts where login = 'archive-out@afsga.muh'
11:46:52 4226 search_open: pgsql "NULL"
11:46:52 4226 search_find: file="NULL"
11:46:52 4226 key="select mailquota from accounts where login = 'archive-out@afsga.muh'" partial=-1 affix=NULL starflags=0
11:46:52 4226 LRU list:
11:46:52 4226 internal_search_find: file="NULL"
11:46:52 4226 type=pgsql key="select mailquota from accounts where login = 'archive-out@afsga.muh'"
11:46:52 4226 database lookup required for select mailquota from accounts where login = 'archive-out@afsga.muh'
11:46:52 4226 PostgreSQL query: select mailquota from accounts where login = 'archive-out@afsga.muh'
11:46:52 4226 PGSQL new connection: socket=/tmp/.s.PGSQL.5432 database=users user=pgsql
11:46:52 4226 lookup yielded: 1000000000
11:46:52 4226 expanding: $value
11:46:52 4226 result: 1000000000
11:46:52 4226 expanding: ${lookup pgsql{select mailquota from accounts where login = '$local_part@$domain'}{$value}fail}
11:46:52 4226 result: 1000000000
11:46:52 4226 changed uid/gid: local delivery to archive-out <archive-out@afsga.muh> transport=my_local_delivery_transport
11:46:52 4226 uid=18 gid=6 pid=4226
11:46:52 4226 auxiliary group list: 6
11:46:52 4226 home=NULL current=/
11:46:52 4226 set_process_info: 4226 delivering 1OKo5k-000165-H6 to archive-out using my_local_delivery_transport
11:46:52 4226 appendfile transport entered
11:46:52 4226 expanding: /var/mail/$local_part@$domain
11:46:52 4226 result: /var/mail/archive-out@afsga.muh
11:46:52 4226 appendfile: mode=660 notify_comsat=0 quota=1000000000 warning=75%
11:46:52 4226 file=/var/mail/archive-out@afsga.muh format=unix
11:46:52 4226 message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
11:46:52 4226 message_suffix=\n
11:46:52 4226 maildir_use_size_file=no
11:46:52 4226 locking by lockfile fcntl
11:46:52 4226 lock name: /var/mail/archive-out@afsga.muh.lock
11:46:52 4226 hitch name: /var/mail/archive-out@afsga.muh.lock.MTS.afsga.muh.4c0a00ec.00001082
11:46:52 4226 lock file created
11:46:52 4226 mailbox /var/mail/archive-out@afsga.muh is locked
11:46:52 4226 writing to file /var/mail/archive-out@afsga.muh
11:46:52 4226 Exim quota = 1000000000 old size = 264819253 this message = 4305 (included)
11:46:52 4226 file count quota = 0 count = 0
11:46:52 4226 condition: def:return_path
11:46:52 4226 result: true
11:46:52 4226 expanding: $return_path
11:46:52 4226 result: admin@afsga.muh
11:46:52 4226 expanding: MAILER-DAEMON
11:46:52 4226 result: MAILER-DAEMON
11:46:52 4226 skipping: result is not used
11:46:52 4226 expanding: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
11:46:52 4226
11:46:52 4226 result: From admin@afsga.muh Sat Jun 05 11:46:52 2010
11:46:52 4226
11:46:52 4226 writing data block fd=7 size=46 timeout=0
11:46:52 4226 writing data block fd=7 size=4414 timeout=0
11:46:52 4226 writing data block fd=7 size=1 timeout=0
11:46:52 4226 quota = 1000000000 threshold = 750000000 old size = 264819253 message size = 4461
11:46:52 4226 appendfile yields 0 with errno=0 more_errno=0
11:46:52 4223 journalling archive-out@afsga.muh
11:46:52 4226 search_tidyup called
11:46:52 4226 close PGSQL connection: (/tmp/.s.PGSQL.5432)/users/pgsql
11:46:52 4223 my_local_delivery_transport transport returned OK for archive-out@afsga.muh
11:46:52 4223 post-process archive-out@afsga.muh (0)
11:46:52 4223 archive-out@afsga.muh delivered
11:46:52 4223 LOG: MAIN
11:46:52 4223 => archive-out <address@yandex.ru> R=my_local_domains_router T=my_local_delivery_transport
11:46:52 4223 >>>>>>>>>>>>>>>> Remote deliveries >>>>>>>>>>>>>>>>
11:46:52 4223 --------> address@yandex.ru <--------
11:46:52 4223 expanding: select extmail from accounts where login = '$sender_address'
11:46:52 4223 result: select extmail from accounts where login = 'admin@afsga.muh'
11:46:52 4223 search_open: pgsql "NULL"
11:46:52 4223 search_find: file="NULL"
11:46:52 4223 key="select extmail from accounts where login = 'admin@afsga.muh'" partial=-1 affix=NULL starflags=0
11:46:52 4223 LRU list:
11:46:52 4223 internal_search_find: file="NULL"
11:46:52 4223 type=pgsql key="select extmail from accounts where login = 'admin@afsga.muh'"
11:46:52 4223 database lookup required for select extmail from accounts where login = 'admin@afsga.muh'
11:46:52 4223 PostgreSQL query: select extmail from accounts where login = 'admin@afsga.muh'
11:46:52 4223 PGSQL new connection: socket=/tmp/.s.PGSQL.5432 database=users user=pgsql
11:46:52 4223 lookup yielded: address@yandex.ru
11:46:52 4223 expanding: $value
11:46:52 4223 result: address@yandex.ru
11:46:52 4223 expanding: ${lookup pgsql{select extmail from accounts where login = '$sender_address'}{$value}fail}
11:46:52 4223 result: address@yandex.ru
11:46:52 4223 search_tidyup called
11:46:52 4223 close PGSQL connection: (/tmp/.s.PGSQL.5432)/users/pgsql
11:46:52 4223 set_process_info: 4223 delivering 1OKo5k-000165-H6: waiting for a remote delivery subprocess to finish
11:46:52 4229 changed uid/gid: remote delivery to address@yandex.ru with transport=remote_smtp
11:46:52 4229 uid=26 gid=6 pid=4229
11:46:52 4223 selecting on subprocess pipes
11:46:52 4229 auxiliary group list: 6
11:46:52 4229 set_process_info: 4229 delivering 1OKo5k-000165-H6 using remote_smtp
11:46:52 4229 remote_smtp transport entered
11:46:52 4229 address@yandex.ru
11:46:52 4229 yandex.ru in queue_smtp_domains? no (option unset)
11:46:52 4229 checking status of smtp.yandex.ru
11:46:52 4229 locking /var/spool/exim/db/retry.lockfile
11:46:52 4229 locked /var/spool/exim/db/retry.lockfile
11:46:52 4229 EXIM_DBOPEN(/var/spool/exim/db/retry)
11:46:52 4229 returned from EXIM_DBOPEN
11:46:52 4229 opened hints database /var/spool/exim/db/retry: flags=O_RDONLY
11:46:52 4229 dbfn_read: key=T:smtp.yandex.ru:77.88.21.38
11:46:52 4229 dbfn_read: key=T:smtp.yandex.ru:77.88.21.38:1OKo5k-000165-H6
11:46:52 4229 no host retry record
11:46:52 4229 no message retry record
11:46:52 4229 smtp.yandex.ru [77.88.21.38] status = usable
11:46:52 4229 77.88.21.38 in serialize_hosts? no (option unset)
11:46:52 4229 delivering 1OKo5k-000165-H6 to smtp.yandex.ru [77.88.21.38] (address@yandex.ru)
11:46:52 4229 set_process_info: 4229 delivering 1OKo5k-000165-H6 to smtp.yandex.ru [77.88.21.38] (address@yandex.ru)
11:46:52 4229 Connecting to smtp.yandex.ru [77.88.21.38]:25 ... connected
11:46:52 4229 expanding: $primary_hostname
11:46:52 4229 result: MTS.afsga.muh
11:46:52 4229 waiting for data on socket
11:46:52 4229 read response data: size=97
11:46:52 4229 SMTP<< 220 smtp1.mail.yandex.net (Want to use Yandex.Mail for your domain? Visit http://pdd.yandex.ru)
11:46:52 4229 77.88.21.38 in hosts_avoid_esmtp? no (option unset)
11:46:52 4229 SMTP>> EHLO MTS.afsga.muh
11:46:52 4229 waiting for data on socket
11:46:53 4229 read response data: size=166
11:46:53 4229 SMTP<< 250-smtp1.mail.yandex.net
11:46:53 4229 250-PIPELINING
11:46:53 4229 250-SIZE 42991616
11:46:53 4229 250-VRFY
11:46:53 4229 250-ETRN
11:46:53 4229 250-STARTTLS
11:46:53 4229 250-AUTH PLAIN LOGIN
11:46:53 4229 250-ENHANCEDSTATUSCODES
11:46:53 4229 250-8BITMIME
11:46:53 4229 250 DSN
11:46:53 4229 77.88.21.38 in hosts_avoid_tls? no (option unset)
11:46:53 4229 SMTP>> STARTTLS
11:46:53 4229 waiting for data on socket
11:46:53 4229 read response data: size=30
11:46:53 4229 SMTP<< 220 2.0.0 Ready to start TLS
11:46:53 4229 Initialized TLS
11:46:53 4229 Calling SSL_connect
11:46:53 4229 SSL info: before/connect initialization
11:46:53 4229 SSL info: before/connect initialization
11:46:53 4229 SSL info: SSLv2/v3 write client hello A
11:46:53 4229 SSL info: SSLv3 read server hello A
11:46:53 4229 SSL info: SSLv3 read server certificate A
11:46:53 4229 SSL info: SSLv3 read server key exchange A
11:46:53 4229 SSL info: SSLv3 read server done A
11:46:53 4229 SSL info: SSLv3 write client key exchange A
11:46:53 4229 SSL info: SSLv3 write change cipher spec A
11:46:53 4229 SSL info: SSLv3 write finished A
11:46:53 4229 SSL info: SSLv3 flush data
11:46:53 4229 SSL info: SSLv3 read finished A
11:46:53 4229 SSL info: SSL negotiation finished successfully
11:46:53 4229 SSL info: SSL negotiation finished successfully
11:46:53 4229 SSL_connect succeeded
11:46:53 4229 Cipher: TLSv1:DHE-RSA-AES256-SHA:256
11:46:53 4229 SMTP>> EHLO MTS.afsga.muh
11:46:53 4229 tls_do_write(bfbfbe40, 20)
11:46:53 4229 SSL_write(SSL, bfbfbe40, 20)
11:46:53 4229 outbytes=20 error=0
11:46:53 4229 waiting for data on socket
11:46:53 4229 Calling SSL_read(8116200, bfbfc240, 4096)
11:46:53 4229 read response data: size=152
11:46:53 4229 SMTP<< 250-smtp1.mail.yandex.net
11:46:53 4229 250-PIPELINING
11:46:53 4229 250-SIZE 42991616
11:46:53 4229 250-VRFY
11:46:53 4229 250-ETRN
11:46:53 4229 250-AUTH PLAIN LOGIN
11:46:53 4229 250-ENHANCEDSTATUSCODES
11:46:53 4229 250-8BITMIME
11:46:53 4229 250 DSN
11:46:53 4229 77.88.21.38 in hosts_avoid_pipelining? no (option unset)
11:46:53 4229 using PIPELINING
;; res_nquerydomain(smtp.yandex.ru, <Nil>, 1, 28)
;; res_query(smtp.yandex.ru, 1, 28)
;; res_nmkquery(QUERY, smtp.yandex.ru, IN, AAAA)
;; res_send()
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 13598
;; flags: rd; QUERY: 1, ANSWER: 0, AUTHORITY: 0, ADDITIONAL: 0
;; smtp.yandex.ru, type = AAAA, class = IN
;; Querying server (# 1) address = 192.168.0.11
;; new DG socket
;; got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 13598
;; flags: qr rd ra; QUERY: 1, ANSWER: 0, AUTHORITY: 1, ADDITIONAL: 0
;; smtp.yandex.ru, type = AAAA, class = IN
yandex.ru. 14m1s IN SOA ns1.yandex.ru. sysadmin.yandex-team.ru. (
2010060411 ; serial
30M ; refresh
15M ; retry
4w2d ; expiry
15M ) ; minimum
;; rcode = (NOERROR), counts = an:0 ns:1 ar:0
res_nsearch failed (-1)
11:46:53 4229 gethostbyname2(af=inet6) returned 4 (NO_DATA)
;; res_nquerydomain(smtp.yandex.ru, <Nil>, 1, 1)
;; res_query(smtp.yandex.ru, 1, 1)
;; res_nmkquery(QUERY, smtp.yandex.ru, IN, A)
;; res_send()
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 13599
;; flags: rd; QUERY: 1, ANSWER: 0, AUTHORITY: 0, ADDITIONAL: 0
;; smtp.yandex.ru, type = A, class = IN
;; Querying server (# 1) address = 192.168.0.11
;; new DG socket
;; got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 13599
;; flags: qr rd ra; QUERY: 1, ANSWER: 3, AUTHORITY: 0, ADDITIONAL: 0
;; smtp.yandex.ru, type = A, class = IN
smtp.yandex.ru. 3m1s IN A 93.158.134.38
smtp.yandex.ru. 3m1s IN A 213.180.204.38
smtp.yandex.ru. 3m1s IN A 77.88.21.38
11:46:53 4229 gethostbyname2 looked up these IP addresses:
11:46:53 4229 name=smtp.yandex.ru address=93.158.134.38
11:46:53 4229 name=smtp.yandex.ru address=213.180.204.38
11:46:53 4229 name=smtp.yandex.ru address=77.88.21.38
11:46:53 4229 77.88.21.38 in hosts_require_auth? yes (matched "smtp.yandex.ru")
11:46:53 4229 scanning authentication mechanisms
11:46:53 4229 SMTP>> AUTH LOGIN
11:46:53 4229 tls_do_write(bfbfbe40, 12)
11:46:53 4229 SSL_write(SSL, bfbfbe40, 12)
11:46:53 4229 outbytes=12 error=0
11:46:53 4229 waiting for data on socket
11:46:53 4229 Calling SSL_read(8116200, bfbfc240, 4096)
11:46:53 4229 read response data: size=18
11:46:53 4229 SMTP<< 334 VXNlcm5hbWU6
11:46:53 4229 expanding: select extmail from accounts where login = '$sender_address'
11:46:53 4229 result: select extmail from accounts where login = 'admin@afsga.muh'
11:46:53 4229 search_open: pgsql "NULL"
11:46:53 4229 search_find: file="NULL"
11:46:53 4229 key="select extmail from accounts where login = 'admin@afsga.muh'" partial=-1 affix=NULL starflags=0
11:46:53 4229 LRU list:
11:46:53 4229 internal_search_find: file="NULL"
11:46:53 4229 type=pgsql key="select extmail from accounts where login = 'admin@afsga.muh'"
11:46:53 4229 database lookup required for select extmail from accounts where login = 'admin@afsga.muh'
11:46:53 4229 PostgreSQL query: select extmail from accounts where login = 'admin@afsga.muh'
11:46:53 4229 PGSQL new connection: socket=/tmp/.s.PGSQL.5432 database=users user=pgsql
11:46:53 4229 lookup yielded: address@yandex.ru
11:46:53 4229 expanding: $value
11:46:53 4229 result: address@yandex.ru
11:46:53 4229 expanding: ${lookup pgsql{select extmail from accounts where login = '$sender_address'}{$value}fail}
11:46:53 4229 result: address@yandex.ru
11:46:53 4229 SMTP>> ************************
11:46:53 4229 tls_do_write(bfbfbe40, 26)
11:46:53 4229 SSL_write(SSL, bfbfbe40, 26)
11:46:53 4229 outbytes=26 error=0
11:46:53 4229 waiting for data on socket
11:46:53 4229 Calling SSL_read(8116200, bfbfc240, 4096)
11:46:53 4229 read response data: size=18
11:46:53 4229 SMTP<< 334 UGFzc3dvcmQ6
11:46:53 4229 expanding: select extmailpwd from accounts where login = '$sender_address'
11:46:53 4229 result: select extmailpwd from accounts where login = 'admin@afsga.muh'
11:46:53 4229 search_open: pgsql "NULL"
11:46:53 4229 cached open
11:46:53 4229 search_find: file="NULL"
11:46:53 4229 key="select extmailpwd from accounts where login = 'admin@afsga.muh'" partial=-1 affix=NULL starflags=0
11:46:53 4229 LRU list:
11:46:53 4229 internal_search_find: file="NULL"
11:46:53 4229 type=pgsql key="select extmailpwd from accounts where login = 'admin@afsga.muh'"
11:46:53 4229 database lookup required for select extmailpwd from accounts where login = 'admin@afsga.muh'
11:46:53 4229 PostgreSQL query: select extmailpwd from accounts where login = 'admin@afsga.muh'
11:46:53 4229 PGSQL using cached connection for (/tmp/.s.PGSQL.5432)/users/pgsql
11:46:53 4229 lookup yielded: tnzheka7
11:46:53 4229 expanding: $value
11:46:53 4229 result: tnzheka7
11:46:53 4229 expanding: ${lookup pgsql{select extmailpwd from accounts where login = '$sender_address'}{$value}fail}
11:46:53 4229 result: tnzheka7
11:46:53 4229 SMTP>> ************
11:46:53 4229 tls_do_write(bfbfbe40, 14)
11:46:53 4229 SSL_write(SSL, bfbfbe40, 14)
11:46:53 4229 outbytes=14 error=0
11:46:53 4229 waiting for data on socket
11:46:53 4229 Calling SSL_read(8116200, bfbfc240, 4096)
11:46:53 4229 read response data: size=37
11:46:53 4229 SMTP<< 235 2.7.0 Authentication successful
11:46:53 4229 mail_login authenticator yielded 0
11:46:53 4229 SMTP>> MAIL FROM:<address@yandex.ru> SIZE=5463
11:46:53 4229 SMTP>> RCPT TO:<address@yandex.ru>
11:46:53 4229 SMTP>> DATA
11:46:53 4229 tls_do_write(bfbfbe40, 76)
11:46:53 4229 SSL_write(SSL, bfbfbe40, 76)
11:46:53 4229 outbytes=76 error=0
11:46:53 4229 waiting for data on socket
11:46:53 4229 Calling SSL_read(8116200, bfbfc240, 4096)
11:46:53 4229 read response data: size=65
11:46:53 4229 SMTP<< 250 2.1.0 Ok
11:46:53 4229 SMTP<< 250 2.1.5 Ok
11:46:53 4229 SMTP<< 354 End data with <CR><LF>.<CR><LF>
11:46:53 4229 SMTP>> writing message and terminating "."
11:46:53 4229 condition: def:h_Disposition-Notification-To:
11:46:53 4229 result: false
11:46:53 4229 expanding: Disposition-Notification-To
11:46:53 4229 result: Disposition-Notification-To
11:46:53 4229 skipping: result is not used
11:46:53 4229 expanding: ${if def:h_Disposition-Notification-To: {Disposition-Notification-To}}
11:46:53 4229 result:
11:46:53 4229 rewrite_one_header: type=F:
11:46:53 4229 From: "Astrakhan SGA #1" <admin@afsga.muh>
11:46:53 4229 address match: subject=admin@afsga.muh pattern=*@afsga.muh
11:46:53 4229 afsga.muh in "afsga.muh"? yes (matched "afsga.muh")
11:46:53 4229 admin@afsga.muh in "*@afsga.muh"? yes (matched "*@afsga.muh")
11:46:53 4229 expanding: select extmail from accounts where login = '$sender_address'
11:46:53 4229 result: select extmail from accounts where login = 'admin@afsga.muh'
11:46:53 4229 search_open: pgsql "NULL"
11:46:53 4229 cached open
11:46:53 4229 search_find: file="NULL"
11:46:53 4229 key="select extmail from accounts where login = 'admin@afsga.muh'" partial=-1 affix=NULL starflags=0
11:46:53 4229 LRU list:
11:46:53 4229 internal_search_find: file="NULL"
11:46:53 4229 type=pgsql key="select extmail from accounts where login = 'admin@afsga.muh'"
11:46:53 4229 cached data used for lookup of select extmail from accounts where login = 'admin@afsga.muh'
11:46:53 4229 lookup yielded: address@yandex.ru
11:46:53 4229 expanding: $value
11:46:53 4229 result: address@yandex.ru
11:46:53 4229 expanding: ${lookup pgsql{select extmail from accounts where login = '$sender_address'}{$value}fail}
11:46:53 4229 result: address@yandex.ru
11:46:53 4229 LOG: address_rewrite MAIN
11:46:53 4229 "admin@afsga.muh" from from: rewritten as "address@yandex.ru" by rule 1
11:46:53 4229 newlen=45 newtype=F newtext:
11:46:53 4229 From: "Astrakhan SGA #1" <address@yandex.ru>
11:46:53 4229 remainder:
11:46:53 4229 rewrite_one_header: type=T:
11:46:53 4229 To: <address@yandex.ru>
11:46:53 4229 address match: subject=address@yandex.ru pattern=*@afsga.muh
11:46:53 4229 yandex.ru in "afsga.muh"? no (end of list)
11:46:53 4229 address@yandex.ru in "*@afsga.muh"? no (end of list)
11:46:53 4229 condition: def:h_Disposition-Notification-To:
11:46:53 4229 result: false
11:46:53 4229 expanding: select extmail from accounts where login = '$sender_address'
11:46:53 4229 result: select extmail from accounts where login = ''
11:46:53 4229 skipping: result is not used
11:46:53 4229 expanding: $value
11:46:53 4229 result:
11:46:53 4229 skipping: result is not used
11:46:53 4229 expanding: Disposition-Notification-To: ${lookup pgsql{select extmail from accounts where login = '$sender_address'}{$value}fail}
11:46:53 4229 result: Disposition-Notification-To:
11:46:53 4229 skipping: result is not used
11:46:53 4229 expanding: ${if def:h_Disposition-Notification-To: {Disposition-Notification-To: ${lookup pgsql{select extmail from accounts where login = '$sender_address'}{$value}fail} } }
11:46:53 4229 result:
11:46:53 4229 writing data block fd=7 size=4445 timeout=300
11:46:53 4229 tls_do_write(812f000, 4445)
11:46:53 4229 SSL_write(SSL, 812f000, 4445)
11:46:53 4229 outbytes=4445 error=0
11:46:53 4229 waiting for data on socket
11:46:53 4229 Calling SSL_read(8116200, bfbfc240, 4096)
11:46:53 4229 read response data: size=62
11:46:53 4229 SMTP<< 250 2.0.0 Ok: queued on smtp1.mail.yandex.net as BB3D9290058
11:46:53 4229 journalling address@yandex.ru/remote_smtp
11:46:53 4229 ok=1 send_quit=1 send_rset=0 continue_more=0 yield=0 first_address is NULL
11:46:53 4229 77.88.21.38 in hosts_nopass_tls? no (option unset)
11:46:53 4229 transport_check_waiting entered
11:46:53 4229 sequence=1 local_max=500 global_max=-1
11:46:53 4229 locking /var/spool/exim/db/wait-remote_smtp.lockfile
11:46:53 4229 locked /var/spool/exim/db/wait-remote_smtp.lockfile
11:46:53 4229 EXIM_DBOPEN(/var/spool/exim/db/wait-remote_smtp)
11:46:53 4229 returned from EXIM_DBOPEN
11:46:53 4229 opened hints database /var/spool/exim/db/wait-remote_smtp: flags=O_RDWR
11:46:53 4229 dbfn_read: key=smtp.yandex.ru
11:46:53 4229 no messages waiting for smtp.yandex.ru
11:46:53 4229 SMTP>> QUIT
11:46:53 4229 tls_do_write(bfbfbe40, 6)
11:46:53 4229 SSL_write(SSL, bfbfbe40, 6)
11:46:53 4229 outbytes=6 error=0
11:46:53 4229 tls_close(): shutting down SSL
11:46:53 4229 SSL info: SSL negotiation finished successfully
11:46:53 4229 set_process_info: 4229 delivering 1OKo5k-000165-H6: just tried smtp.yandex.ru [77.88.21.38] for address@yandex.ru: result OK
11:46:53 4229 Leaving remote_smtp transport
11:46:53 4229 set_process_info: 4229 delivering 1OKo5k-000165-H6 (just run remote_smtp for address@yandex.ru in subprocess)
11:46:53 4229 search_tidyup called
11:46:53 4229 close PGSQL connection: (/tmp/.s.PGSQL.5432)/users/pgsql
11:46:53 4223 reading pipe for subprocess 4229 (not ended)
11:46:53 4223 read() yielded 187
11:46:53 4223 Z0 item read
11:46:53 4223 remote delivery process 4229 ended
11:46:53 4223 set_process_info: 4223 delivering 1OKo5k-000165-H6
11:46:53 4223 post-process address@yandex.ru (0)
11:46:53 4223 address@yandex.ru delivered
11:46:53 4223 address@yandex.ru: children all complete
11:46:53 4223 LOG: MAIN
11:46:53 4223 => address@yandex.ru R=send_to_gateway T=remote_smtp H=smtp.yandex.ru [77.88.21.38] X=TLSv1:DHE-RSA-AES256-SHA:256
11:46:53 4223 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
11:46:53 4223 changed uid/gid: post-delivery tidying
11:46:53 4223 uid=26 gid=6 pid=4223
11:46:53 4223 auxiliary group list: 6
11:46:53 4223 set_process_info: 4223 tidying up after delivering 1OKo5k-000165-H6
11:46:53 4223 Processing retry items
11:46:53 4223 Succeeded addresses:
11:46:53 4223 address@yandex.ru: no retry items
11:46:53 4223 address@yandex.ru: no retry items
11:46:53 4223 archive-out@afsga.muh: no retry items
11:46:53 4223 address@yandex.ru: no retry items
11:46:53 4223 address@yandex.ru: no retry items
11:46:53 4223 address@yandex.ru: no retry items
11:46:53 4223 address@yandex.ru: no retry items
11:46:53 4223 Failed addresses:
11:46:53 4223 Deferred addresses:
11:46:53 4223 end of retry processing
11:46:53 4223 LOG: MAIN
11:46:53 4223 Completed
11:46:53 4223 end delivery of 1OKo5k-000165-H6
11:46:53 4223 search_tidyup called
11:46:53 4223 search_tidyup called
11:46:53 4223 >>>>>>>>>>>>>>>> Exim pid=4223 terminating with rc=0 >>>>>>>>>>>>>>>>
11:46:55 4221 SMTP<< QUIT
11:46:55 4221 SMTP>> 221 MTS.afsga.muh closing connection
11:46:55 4221 LOG: smtp_connection MAIN
11:46:55 4221 SMTP connection from (homepc) [192.168.0.14] closed by QUIT
11:46:55 4221 search_tidyup called
11:46:55 4220 child 4221 ended: status=0x0
11:46:55 4220 0 SMTP accept processes now running
11:46:55 4220 Listening...