ПРоблема с Exim после обновления

EXIM, sendmail, postfix, Dovecot и прочие. Решение проблем связанных с работой электронной почты

Модератор: xM

Правила форума
Убедительная просьба юзать теги [code] при оформлении листингов.
Сообщения не оформленные должным образом имеют все шансы быть незамеченными.
Nick_mad
сержант
Сообщения: 280
Зарегистрирован: 2008-05-30 12:16:02
Откуда: Киев
Контактная информация:

ПРоблема с Exim после обновления

Непрочитанное сообщение Nick_mad » 2014-08-26 18:07:37

Здравствуйте.

После обновления Exim перестала приходить почта. В логах

Код: Выделить всё

2014-08-26 10:41:11 1XMBNP-000NjE-9v == nick@domain.org.ua R=virtual_domains defer (-1): Temporary internal error
Присмотревшись exim -d+all -M 1XMBNP-000NjE-9v

Код: Выделить всё

11:30:25 91646 Exim version 4.84 (FreeBSD 8.4) uid=0 gid=0 pid=91646 D=fffdffff
Probably Berkeley DB version 1.8x (native mode)
Support for: crypteq iconv() use_setclassresources PAM Perl Expand_dlfunc OpenSSL Content_Scanning DKIM Old_Demime PRDR
Lookups (built-in): lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmjz 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
Compiler: GCC [4.2.1 20070831 patched [FreeBSD]]
Library version: OpenSSL: Compile: OpenSSL 0.9.8y 5 Feb 2013
                          Runtime: OpenSSL 0.9.8y 5 Feb 2013
                                 : built on: date not available
Library version: PCRE: Compile: 8.35
                       Runtime: 8.35 2014-04-04
11:30:25 91646 Total 14 lookups
Library version: MySQL: Compile: 5.5.39 [Source distribution]
                        Runtime: 5.5.39
WHITELIST_D_MACROS unset
TRUSTED_CONFIG_LIST unset
11:30:25 91646 changed uid/gid: forcing real = effective
11:30:25 91646   uid=0 gid=0 pid=91646
11:30:25 91646   auxiliary group list: 0
11:30:25 91646 seeking password data for user "www": cache not available
11:30:25 91646 getpwnam() succeeded uid=80 gid=80
11:30:25 91646 seeking password data for user "mailnull": cache not available
11:30:25 91646 getpwnam() succeeded uid=26 gid=26
11:30:25 91646 seeking password data for user "root": cache not available
11:30:25 91646 getpwnam() succeeded uid=0 gid=0
11:30:25 91647 changed uid/gid: calling tls_validate_require_cipher
11:30:25 91647   uid=26 gid=6 pid=91647
11:30:25 91647   auxiliary group list: 6
11:30:25 91646 tls_validate_require_cipher child 91647 ended: status=0x0
11:30:25 91646 configuration file is /usr/local/etc/exim/configure
11:30:25 91646 log selectors = 00000ffc 002b2001
11:30:25 91646 cwd=/usr/local/etc/exim 4 args: exim -d+all -M 1XMBNP-000NjE-9v
11:30:25 91646 trusted user
11:30:25 91646 admin user
11:30:25 91646 seeking password data for user "mailnull": cache not available
11:30:25 91646 getpwnam() succeeded uid=26 gid=26
11:30:25 91646 seeking password data for user "mailnull": using cached result
11:30:25 91646 getpwnam() succeeded uid=26 gid=26
11:30:25 91646 set_process_info: 91646 delivering specified messages
11:30:25 91646 set_process_info: 91646 delivering 1XMBNP-000NjE-9v
11:30:25 91646 reading spool file 1XMBNP-000NjE-9v-H
11:30:25 91646 user=mailnull uid=26 gid=6 sender=nick_mad@ukr.net
11:30:25 91646 sender_fullhost = frv154.fwdcdn.com [212.42.77.154]
11:30:25 91646 sender_rcvhost = frv154.fwdcdn.com ([212.42.77.154])
11:30:25 91646 sender_local=0 ident=unset
11:30:25 91646 Non-recipients:
11:30:25 91646 Empty Tree
11:30:25 91646 ---- End of tree ----
11:30:25 91646 recipients_count=1
11:30:25 91646 body_linecount=11 message_linecount=25
11:30:25 91646 Delivery address list:
11:30:25 91646   nick@domain.org.ua 
11:30:25 91646 locking /var/spool/exim/db/retry.lockfile
11:30:25 91646 locked /var/spool/exim/db/retry.lockfile
11:30:25 91646 EXIM_DBOPEN(/var/spool/exim/db/retry)
11:30:25 91646 returned from EXIM_DBOPEN
11:30:25 91646 opened hints database /var/spool/exim/db/retry: flags=O_RDONLY
11:30:25 91646 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
11:30:25 91646 Considering: nick@domain.org.ua
11:30:25 91646 unique = nick@domain.org.ua
11:30:25 91646 dbfn_read: key=R:domain.org.ua
11:30:25 91646 dbfn_read: key=R:nick@domain.org.ua
11:30:25 91646 no domain retry record
11:30:25 91646 nick@domain.org.ua: queued for routing
11:30:25 91646 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
11:30:25 91646 routing nick@domain.org.ua
11:30:25 91646 --------> dnslookup router <--------
11:30:25 91646 local_part=nick domain=domain.org.ua
11:30:25 91646 checking domains
11:30:25 91646 expanding: $domain
11:30:25 91646    result: domain.org.ua
11:30:25 91646 expanding: SELECT DISTINCT domain FROM domains WHERE type = 'local' AND enabled = '1' AND domain = '${quote_mysql:$domain}'
11:30:25 91646    result: SELECT DISTINCT domain FROM domains WHERE type = 'local' AND enabled = '1' AND domain = 'domain.org.ua'
11:30:25 91646 search_open: mysql "NULL"
11:30:25 91646 search_find: file="NULL"
11:30:25 91646   key="SELECT DISTINCT domain FROM domains WHERE type = 'local' AND enabled = '1' AND domain = 'domain.org.ua'" partial=-1 affix=NULL starflags=0
11:30:25 91646 LRU list:
11:30:25 91646 internal_search_find: file="NULL"
11:30:25 91646   type=mysql key="SELECT DISTINCT domain FROM domains WHERE type = 'local' AND enabled = '1' AND domain = 'domain.org.ua'"
11:30:25 91646 database lookup required for SELECT DISTINCT domain FROM domains WHERE type = 'local' AND enabled = '1' AND domain = 'domain.org.ua'
11:30:25 91646 MySQL query: SELECT DISTINCT domain FROM domains WHERE type = 'local' AND enabled = '1' AND domain = 'domain.org.ua'
11:30:25 91646 MYSQL new connection: host=localhost port=0 socket=/tmp/mysql.sock database=vexim user=vexim
11:30:25 91646 lookup yielded: domain.org.ua
11:30:25 91646 expanding: $domain
11:30:25 91646    result: domain.org.ua
11:30:25 91646 expanding: SELECT DISTINCT alias FROM domainalias WHERE alias = '${quote_mysql:$domain}'
11:30:25 91646    result: SELECT DISTINCT alias FROM domainalias WHERE alias = 'domain.org.ua'
11:30:25 91646 search_open: mysql "NULL"
11:30:25 91646   cached open
11:30:25 91646 search_find: file="NULL"
11:30:25 91646   key="SELECT DISTINCT alias FROM domainalias WHERE alias = 'domain.org.ua'" partial=-1 affix=NULL starflags=0
11:30:25 91646 LRU list:
11:30:25 91646 internal_search_find: file="NULL"
11:30:25 91646   type=mysql key="SELECT DISTINCT alias FROM domainalias WHERE alias = 'domain.org.ua'"
11:30:25 91646 database lookup required for SELECT DISTINCT alias FROM domainalias WHERE alias = 'domain.org.ua'
11:30:25 91646 MySQL query: SELECT DISTINCT alias FROM domainalias WHERE alias = 'domain.org.ua'
11:30:25 91646 MYSQL using cached connection for localhost:(/tmp/mysql.sock)/vexim/vexim
11:30:25 91646 MYSQL: no data found
11:30:25 91646 lookup failed
11:30:25 91646 expanding: @ : ${lookup mysql{SELECT DISTINCT domain FROM domains WHERE type = 'local' AND enabled = '1' AND domain = '${quote_mysql:$domain}'}} : ${lookup mysql{SELECT DISTINCT alias FROM domainalias WHERE alias = '${quote_mysql:$domain}'}}
11:30:25 91646    result: @ : domain.org.ua : 
11:30:25 91646 domain.org.ua in "@ : domain.org.ua : "? yes (matched "@")
11:30:25 91646 domain.org.ua in "! +local_domains"? no (matched "! +local_domains")
11:30:25 91646 dnslookup router skipped: domains mismatch
11:30:25 91646 --------> mailman_router router <--------
11:30:25 91646 local_part=nick domain=domain.org.ua
11:30:25 91646 checking require_files
11:30:25 91646 expanding: /usr/local/mailman/lists/$local_part/config.pck
11:30:25 91646    result: /usr/local/mailman/lists/nick/config.pck
11:30:25 91646 file check: /usr/local/mailman/lists/$local_part/config.pck
11:30:25 91646 expanded file: /usr/local/mailman/lists/nick/config.pck
11:30:25 91646 stat() yielded -1
11:30:25 91646 errno = 2
11:30:25 91646 mailman_router router skipped: file check
11:30:25 91646 --------> ditch_maxmsgsize router <--------
11:30:25 91646 local_part=nick domain=domain.org.ua
11:30:25 91646 checking "condition"
11:30:25 91646 expanding: NULL
11:30:25 91646    result: NULL
11:30:25 91646 considering bool_lax: NULL
11:30:25 91646 expanding: $message_size
11:30:25 91646    result: 1929
11:30:25 91646 expanding: $local_part
11:30:25 91646    result: nick
11:30:25 91646 expanding: $domain
11:30:25 91646    result: domain.org.ua
11:30:25 91646 expanding: select users.maxmsgsize from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.maxmsgsize > 0 and users.domain_id=domains.domain_id 
11:30:25 91646    result: select users.maxmsgsize from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.maxmsgsize > 0 and users.domain_id=domains.domain_id 
11:30:25 91646 search_open: mysql "NULL"
11:30:25 91646   cached open
11:30:25 91646 search_find: file="NULL"
11:30:25 91646   key="select users.maxmsgsize from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.maxmsgsize > 0 and users.domain_id=domains.domain_id " partial=-1 affix=NULL starflags=0
11:30:25 91646 LRU list:
11:30:25 91646 internal_search_find: file="NULL"
11:30:25 91646   type=mysql key="select users.maxmsgsize from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.maxmsgsize > 0 and users.domain_id=domains.domain_id "
11:30:25 91646 database lookup required for select users.maxmsgsize from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.maxmsgsize > 0 and users.domain_id=domains.domain_id 
11:30:25 91646 MySQL query: select users.maxmsgsize from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.maxmsgsize > 0 and users.domain_id=domains.domain_id 
11:30:25 91646 MYSQL using cached connection for localhost:(/tmp/mysql.sock)/vexim/vexim
11:30:25 91646 MYSQL: no data found
11:30:25 91646 lookup failed
11:30:25 91646 expanding: ${value}K
11:30:25 91646    result: K
11:30:25 91646 skipping: result is not used
11:30:25 91646 failed to expand: ${lookup mysql{select users.maxmsgsize from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.maxmsgsize > 0 and users.domain_id=domains.domain_id }{${value}K}fail}} {yes}{no}}}}}}
11:30:25 91646    error message: "lookup" failed and "fail" requested
11:30:25 91646 failure was forced
11:30:25 91646 failed to expand: ${if >{$message_size}{${lookup mysql{select users.maxmsgsize from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.maxmsgsize > 0 and users.domain_id=domains.domain_id }{${value}K}fail}} {yes}{no}}}}}}
11:30:25 91646    error message: "lookup" failed and "fail" requested
11:30:25 91646 failure was forced
11:30:25 91646 failed to expand: ${if and{{bool_lax{NULL}}{bool_lax{${if >{$message_size}{${lookup mysql{select users.maxmsgsize from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.maxmsgsize > 0 and users.domain_id=domains.domain_id }{${value}K}fail}} {yes}{no}}}}}}
11:30:25 91646    error message: "lookup" failed and "fail" requested inside "and{...}" condition
11:30:25 91646 failure was forced
11:30:25 91646 ditch_maxmsgsize router skipped: condition failure
11:30:25 91646 --------> ditch_malware router <--------
11:30:25 91646 local_part=nick domain=domain.org.ua
11:30:25 91646 checking "condition"
11:30:25 91646 expanding: NULL
11:30:25 91646    result: NULL
11:30:25 91646 considering bool_lax: NULL
11:30:25 91646 expanding: $h_X-ACL-Warn:
11:30:25 91646    result: 
11:30:25 91646 expanding: .*malware.*
11:30:25 91646    result: .*malware.*
11:30:25 91646 expanding: $local_part
11:30:25 91646    result: 
11:30:25 91646 skipping: result is not used
11:30:25 91646 expanding: $domain
11:30:25 91646    result: 
11:30:25 91646 skipping: result is not used
11:30:25 91646 expanding: select users.on_avscan from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_avscan = '1' and users.domain_id=domains.domain_id
11:30:25 91646    result: select users.on_avscan from users,domains where localpart = '' and domain = '' and users.on_avscan = '1' and users.domain_id=domains.domain_id
11:30:25 91646 skipping: result is not used
11:30:25 91646 expanding: ${lookup mysql{select users.on_avscan from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_avscan = '1' and users.domain_id=domains.domain_id}}
11:30:25 91646    result: 
11:30:25 91646 skipping: result is not used
11:30:25 91646 expanding: 1
11:30:25 91646    result: 1
11:30:25 91646 skipping: result is not used
11:30:25 91646 condition: and { {match {$h_X-ACL-Warn:}{.*malware.*}} {eq {${lookup mysql{select users.on_avscan from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_avscan = '1' and users.domain_id=domains.domain_id}}}{1} }}
11:30:25 91646    result: false
11:30:25 91646 expanding: yes
11:30:25 91646    result: yes
11:30:25 91646 skipping: result is not used
11:30:25 91646 expanding: no
11:30:25 91646    result: no
11:30:25 91646 expanding: ${if and { {match {$h_X-ACL-Warn:}{.*malware.*}} {eq {${lookup mysql{select users.on_avscan from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_avscan = '1' and users.domain_id=domains.domain_id}}}{1} }} {yes}{no} }
11:30:25 91646    result: no
11:30:25 91646 considering bool_lax: no
11:30:25 91646 condition: and{{bool_lax{NULL}}{bool_lax{${if and { {match {$h_X-ACL-Warn:}{.*malware.*}} {eq {${lookup mysql{select users.on_avscan from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_avscan = '1' and users.domain_id=domains.domain_id}}}{1} }} {yes}{no} }}}}
11:30:25 91646    result: false
11:30:25 91646 expanding: ${if and{{bool_lax{NULL}}{bool_lax{${if and { {match {$h_X-ACL-Warn:}{.*malware.*}} {eq {${lookup mysql{select users.on_avscan from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_avscan = '1' and users.domain_id=domains.domain_id}}}{1} }} {yes}{no} }}}}}
11:30:25 91646    result: 
11:30:25 91646 ditch_malware router skipped: condition failure
11:30:25 91646 --------> ditch_spam router <--------
11:30:25 91646 local_part=nick domain=domain.org.ua
11:30:25 91646 checking "condition"
11:30:25 91646 expanding: NULL
11:30:25 91646    result: NULL
11:30:25 91646 considering bool_lax: NULL
11:30:25 91646 expanding: $spam_score_int
11:30:25 91646    result: 
11:30:25 91646 empty string cast to zero for numerical comparison
11:30:25 91646 expanding: $local_part
11:30:25 91646    result: nick
11:30:25 91646 expanding: $domain
11:30:25 91646    result: domain.org.ua
11:30:25 91646 expanding: select users.sa_refuse * 10 from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_spamassassin = '1' and users.domain_id=domains.domain_id and users.sa_refuse > 0 
11:30:25 91646    result: select users.sa_refuse * 10 from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_spamassassin = '1' and users.domain_id=domains.domain_id and users.sa_refuse > 0 
11:30:25 91646 search_open: mysql "NULL"
11:30:25 91646   cached open
11:30:25 91646 search_find: file="NULL"
11:30:25 91646   key="select users.sa_refuse * 10 from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_spamassassin = '1' and users.domain_id=domains.domain_id and users.sa_refuse > 0 " partial=-1 affix=NULL starflags=0
11:30:25 91646 LRU list:
11:30:25 91646 internal_search_find: file="NULL"
11:30:25 91646   type=mysql key="select users.sa_refuse * 10 from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_spamassassin = '1' and users.domain_id=domains.domain_id and users.sa_refuse > 0 "
11:30:25 91646 database lookup required for select users.sa_refuse * 10 from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_spamassassin = '1' and users.domain_id=domains.domain_id and users.sa_refuse > 0 
11:30:25 91646 MySQL query: select users.sa_refuse * 10 from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_spamassassin = '1' and users.domain_id=domains.domain_id and users.sa_refuse > 0 
11:30:25 91646 MYSQL using cached connection for localhost:(/tmp/mysql.sock)/vexim/vexim
11:30:25 91646 MYSQL: no data found
11:30:25 91646 lookup failed
11:30:25 91646 expanding: $value
11:30:25 91646    result: 
11:30:25 91646 skipping: result is not used
11:30:25 91646 failed to expand: ${lookup mysql{select users.sa_refuse * 10 from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_spamassassin = '1' and users.domain_id=domains.domain_id and users.sa_refuse > 0 }{$value}fail}} {yes}{no}}}}}}
11:30:25 91646    error message: "lookup" failed and "fail" requested
11:30:25 91646 failure was forced
11:30:25 91646 failed to expand: ${if >{$spam_score_int}{${lookup mysql{select users.sa_refuse * 10 from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_spamassassin = '1' and users.domain_id=domains.domain_id and users.sa_refuse > 0 }{$value}fail}} {yes}{no}}}}}}
11:30:25 91646    error message: "lookup" failed and "fail" requested
11:30:25 91646 failure was forced
11:30:25 91646 failed to expand: ${if and{{bool_lax{NULL}}{bool_lax{${if >{$spam_score_int}{${lookup mysql{select users.sa_refuse * 10 from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_spamassassin = '1' and users.domain_id=domains.domain_id and users.sa_refuse > 0 }{$value}fail}} {yes}{no}}}}}}
11:30:25 91646    error message: "lookup" failed and "fail" requested inside "and{...}" condition
11:30:25 91646 failure was forced
11:30:25 91646 ditch_spam router skipped: condition failure
11:30:25 91646 --------> ditch_hdrmailer router <--------
11:30:25 91646 local_part=nick domain=domain.org.ua
11:30:25 91646 checking "condition"
11:30:25 91646 expanding: NULL
11:30:25 91646    result: NULL
11:30:25 91646 considering bool_lax: NULL
11:30:25 91646 expanding: $h_x-mailer:
11:30:25 91646    result: 
11:30:25 91646 expanding: $local_part
11:30:25 91646    result: nick
11:30:25 91646 expanding: $domain
11:30:25 91646    result: domain.org.ua
11:30:25 91646 expanding: select count(*) from blocklists,users,domains where blocklists.blockhdr = 'x-mailer' and blocklists.blockval = '${quote_mysql:$h_x-mailer:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id
11:30:25 91646    result: select count(*) from blocklists,users,domains where blocklists.blockhdr = 'x-mailer' and blocklists.blockval = '' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id
11:30:25 91646 search_open: mysql "NULL"
11:30:25 91646   cached open
11:30:25 91646 search_find: file="NULL"
11:30:25 91646   key="select count(*) from blocklists,users,domains where blocklists.blockhdr = 'x-mailer' and blocklists.blockval = '' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id" partial=-1 affix=NULL starflags=0
11:30:25 91646 LRU list:
11:30:25 91646 internal_search_find: file="NULL"
11:30:25 91646   type=mysql key="select count(*) from blocklists,users,domains where blocklists.blockhdr = 'x-mailer' and blocklists.blockval = '' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id"
11:30:25 91646 database lookup required for select count(*) from blocklists,users,domains where blocklists.blockhdr = 'x-mailer' and blocklists.blockval = '' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id
11:30:25 91646 MySQL query: select count(*) from blocklists,users,domains where blocklists.blockhdr = 'x-mailer' and blocklists.blockval = '' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id
11:30:25 91646 MYSQL using cached connection for localhost:(/tmp/mysql.sock)/vexim/vexim
11:30:25 91646 lookup yielded: 0
11:30:25 91646 expanding: ${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'x-mailer' and blocklists.blockval = '${quote_mysql:$h_x-mailer:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}
11:30:25 91646    result: 0
11:30:25 91646 expanding: 1
11:30:25 91646    result: 1
11:30:25 91646 condition: eq {${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'x-mailer' and blocklists.blockval = '${quote_mysql:$h_x-mailer:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}}{1}
11:30:25 91646    result: false
11:30:25 91646 expanding: yes
11:30:25 91646    result: yes
11:30:25 91646 skipping: result is not used
11:30:25 91646 expanding: no
11:30:25 91646    result: no
11:30:25 91646 expanding: ${if eq {${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'x-mailer' and blocklists.blockval = '${quote_mysql:$h_x-mailer:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}}{1} {yes}{no}}
11:30:25 91646    result: no
11:30:25 91646 considering bool_lax: no
11:30:25 91646 condition: and{{bool_lax{NULL}}{bool_lax{${if eq {${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'x-mailer' and blocklists.blockval = '${quote_mysql:$h_x-mailer:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}}{1} {yes}{no}}}}}
11:30:25 91646    result: false
11:30:25 91646 expanding: ${if and{{bool_lax{NULL}}{bool_lax{${if eq {${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'x-mailer' and blocklists.blockval = '${quote_mysql:$h_x-mailer:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}}{1} {yes}{no}}}}}}
11:30:25 91646    result: 
11:30:25 91646 ditch_hdrmailer router skipped: condition failure
11:30:25 91646 --------> ditch_hdrto router <--------
11:30:25 91646 local_part=nick domain=domain.org.ua
11:30:25 91646 checking "condition"
11:30:25 91646 expanding: NULL
11:30:25 91646    result: NULL
11:30:25 91646 considering bool_lax: NULL
11:30:25 91646 iconv error translating "=?UTF-8?B?0J3QuNC60L7Qu9Cw0Lkg0J/QsNCy0LvRjtC60L7QstC10YY=?=" to ISO-8859-1: Illegal byte sequence
11:30:25 91646 expanding: $h_to:
11:30:25 91646    result: Николай 
11:30:25 91646  <nick@domain.org.ua>
11:30:25 91646 expanding: $local_part
11:30:25 91646    result: nick
11:30:25 91646 expanding: $domain
11:30:25 91646    result: domain.org.ua
11:30:25 91646 expanding: select count(*) from blocklists,users,domains where blocklists.blockhdr = 'to' and blocklists.blockval = '${quote_mysql:$h_to:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id
11:30:25 91646    result: select count(*) from blocklists,users,domains where blocklists.blockhdr = 'to' and blocklists.blockval = 'Николай \n <nick@domain.org.ua>' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id
11:30:25 91646 search_open: mysql "NULL"
11:30:25 91646   cached open
11:30:25 91646 search_find: file="NULL"
11:30:25 91646   key="select count(*) from blocklists,users,domains where blocklists.blockhdr = 'to' and blocklists.blockval = 'Николай \n <nick@domain.org.ua>' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id" partial=-1 affix=NULL starflags=0
11:30:25 91646 LRU list:
11:30:25 91646 internal_search_find: file="NULL"
11:30:25 91646   type=mysql key="select count(*) from blocklists,users,domains where blocklists.blockhdr = 'to' and blocklists.blockval = 'Николай \n <nick@domain.org.ua>' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id"
11:30:25 91646 database lookup required for select count(*) from blocklists,users,domains where blocklists.blockhdr = 'to' and blocklists.blockval = 'Николай \n <nick@domain.org.ua>' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id
11:30:25 91646 MySQL query: select count(*) from blocklists,users,domains where blocklists.blockhdr = 'to' and blocklists.blockval = 'Николай \n <nick@domain.org.ua>' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id
11:30:25 91646 MYSQL using cached connection for localhost:(/tmp/mysql.sock)/vexim/vexim
11:30:25 91646 lookup yielded: 0
11:30:25 91646 expanding: ${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'to' and blocklists.blockval = '${quote_mysql:$h_to:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}
11:30:25 91646    result: 0
11:30:25 91646 expanding: 1
11:30:25 91646    result: 1
11:30:25 91646 condition: eq {${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'to' and blocklists.blockval = '${quote_mysql:$h_to:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}}{1}
11:30:25 91646    result: false
11:30:25 91646 expanding: yes
11:30:25 91646    result: yes
11:30:25 91646 skipping: result is not used
11:30:25 91646 expanding: no
11:30:25 91646    result: no
11:30:25 91646 expanding: ${if eq {${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'to' and blocklists.blockval = '${quote_mysql:$h_to:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}}{1} {yes}{no}}
11:30:25 91646    result: no
11:30:25 91646 considering bool_lax: no
11:30:25 91646 condition: and{{bool_lax{NULL}}{bool_lax{${if eq {${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'to' and blocklists.blockval = '${quote_mysql:$h_to:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}}{1} {yes}{no}}}}}
11:30:25 91646    result: false
11:30:25 91646 expanding: ${if and{{bool_lax{NULL}}{bool_lax{${if eq {${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'to' and blocklists.blockval = '${quote_mysql:$h_to:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}}{1} {yes}{no}}}}}}
11:30:25 91646    result: 
11:30:25 91646 ditch_hdrto router skipped: condition failure
11:30:25 91646 --------> ditch_hdrfrom router <--------
11:30:25 91646 local_part=nick domain=domain.org.ua
11:30:25 91646 checking "condition"
11:30:25 91646 expanding: NULL
11:30:25 91646    result: NULL
11:30:25 91646 considering bool_lax: NULL
11:30:25 91646 iconv error translating "=?UTF-8?B?0J3QuNC60L7Qu9Cw0Lkg0J/QsNCy0LvRjtC60L7QstC10YY=?=" to ISO-8859-1: Illegal byte sequence
11:30:25 91646 expanding: $h_from:
11:30:25 91646    result: Николай 
11:30:25 91646  <nick_mad@ukr.net>
11:30:25 91646 expanding: $local_part
11:30:25 91646    result: nick
11:30:25 91646 expanding: $domain
11:30:25 91646    result: domain.org.ua
11:30:25 91646 expanding: select count(*) from blocklists,users,domains where blocklists.blockhdr = 'from' and blocklists.blockval = '${quote_mysql:$h_from:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id
11:30:25 91646    result: select count(*) from blocklists,users,domains where blocklists.blockhdr = 'from' and blocklists.blockval = 'Николай \n <nick_mad@ukr.net>' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id
11:30:25 91646 search_open: mysql "NULL"
11:30:25 91646   cached open
11:30:25 91646 search_find: file="NULL"
11:30:25 91646   key="select count(*) from blocklists,users,domains where blocklists.blockhdr = 'from' and blocklists.blockval = 'Николай \n <nick_mad@ukr.net>' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id" partial=-1 affix=NULL starflags=0
11:30:25 91646 LRU list:
11:30:25 91646 internal_search_find: file="NULL"
11:30:25 91646   type=mysql key="select count(*) from blocklists,users,domains where blocklists.blockhdr = 'from' and blocklists.blockval = 'Николай \n <nick_mad@ukr.net>' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id"
11:30:25 91646 database lookup required for select count(*) from blocklists,users,domains where blocklists.blockhdr = 'from' and blocklists.blockval = 'Николай \n <nick_mad@ukr.net>' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id
11:30:25 91646 MySQL query: select count(*) from blocklists,users,domains where blocklists.blockhdr = 'from' and blocklists.blockval = 'Николай \n <nick_mad@ukr.net>' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id
11:30:25 91646 MYSQL using cached connection for localhost:(/tmp/mysql.sock)/vexim/vexim
11:30:25 91646 lookup yielded: 0
11:30:25 91646 expanding: ${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'from' and blocklists.blockval = '${quote_mysql:$h_from:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}
11:30:25 91646    result: 0
11:30:25 91646 expanding: 1
11:30:25 91646    result: 1
11:30:25 91646 condition: eq {${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'from' and blocklists.blockval = '${quote_mysql:$h_from:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}}{1}
11:30:25 91646    result: false
11:30:25 91646 expanding: yes
11:30:25 91646    result: yes
11:30:25 91646 skipping: result is not used
11:30:25 91646 expanding: no
11:30:25 91646    result: no
11:30:25 91646 expanding: ${if eq {${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'from' and blocklists.blockval = '${quote_mysql:$h_from:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}}{1} {yes}{no}}
11:30:25 91646    result: no
11:30:25 91646 considering bool_lax: no
11:30:25 91646 condition: and{{bool_lax{NULL}}{bool_lax{${if eq {${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'from' and blocklists.blockval = '${quote_mysql:$h_from:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}}{1} {yes}{no}}}}}
11:30:25 91646    result: false
11:30:25 91646 expanding: ${if and{{bool_lax{NULL}}{bool_lax{${if eq {${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'from' and blocklists.blockval = '${quote_mysql:$h_from:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}}{1} {yes}{no}}}}}}
11:30:25 91646    result: 
11:30:25 91646 ditch_hdrfrom router skipped: condition failure
11:30:25 91646 --------> ditch_hdrsubject router <--------
11:30:25 91646 local_part=nick domain=domain.org.ua
11:30:25 91646 checking "condition"
11:30:25 91646 expanding: NULL
11:30:25 91646    result: NULL
11:30:25 91646 considering bool_lax: NULL
11:30:25 91646 expanding: $h_subject:
11:30:25 91646    result: hjgfhj
11:30:25 91646 expanding: $local_part
11:30:25 91646    result: nick
11:30:25 91646 expanding: $domain
11:30:25 91646    result: domain.org.ua
11:30:25 91646 expanding: select count(*) from blocklists,users,domains where blocklists.blockhdr = 'subject' and blocklists.blockval = '${quote_mysql:$h_subject:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id
11:30:25 91646    result: select count(*) from blocklists,users,domains where blocklists.blockhdr = 'subject' and blocklists.blockval = 'hjgfhj' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id
11:30:25 91646 search_open: mysql "NULL"
11:30:25 91646   cached open
11:30:25 91646 search_find: file="NULL"
11:30:25 91646   key="select count(*) from blocklists,users,domains where blocklists.blockhdr = 'subject' and blocklists.blockval = 'hjgfhj' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id" partial=-1 affix=NULL starflags=0
11:30:25 91646 LRU list:
11:30:25 91646 internal_search_find: file="NULL"
11:30:25 91646   type=mysql key="select count(*) from blocklists,users,domains where blocklists.blockhdr = 'subject' and blocklists.blockval = 'hjgfhj' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id"
11:30:25 91646 database lookup required for select count(*) from blocklists,users,domains where blocklists.blockhdr = 'subject' and blocklists.blockval = 'hjgfhj' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id
11:30:25 91646 MySQL query: select count(*) from blocklists,users,domains where blocklists.blockhdr = 'subject' and blocklists.blockval = 'hjgfhj' and users.localpart = 'nick' and domains.domain = 'domain.org.ua' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id
11:30:25 91646 MYSQL using cached connection for localhost:(/tmp/mysql.sock)/vexim/vexim
11:30:25 91646 lookup yielded: 0
11:30:25 91646 expanding: ${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'subject' and blocklists.blockval = '${quote_mysql:$h_subject:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}
11:30:25 91646    result: 0
11:30:25 91646 expanding: 1
11:30:25 91646    result: 1
11:30:25 91646 condition: eq {${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'subject' and blocklists.blockval = '${quote_mysql:$h_subject:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}}{1}
11:30:25 91646    result: false
11:30:25 91646 expanding: yes
11:30:25 91646    result: yes
11:30:25 91646 skipping: result is not used
11:30:25 91646 expanding: no
11:30:25 91646    result: no
11:30:25 91646 expanding: ${if eq {${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'subject' and blocklists.blockval = '${quote_mysql:$h_subject:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}}{1} {yes}{no}}
11:30:25 91646    result: no
11:30:25 91646 considering bool_lax: no
11:30:25 91646 condition: and{{bool_lax{NULL}}{bool_lax{${if eq {${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'subject' and blocklists.blockval = '${quote_mysql:$h_subject:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}}{1} {yes}{no}}}}}
11:30:25 91646    result: false
11:30:25 91646 expanding: ${if and{{bool_lax{NULL}}{bool_lax{${if eq {${lookup mysql{select count(*) from blocklists,users,domains where blocklists.blockhdr = 'subject' and blocklists.blockval = '${quote_mysql:$h_subject:}' and users.localpart = '${quote_mysql:$local_part}' and domains.domain = '${quote_mysql:$domain}' and domains.domain_id=blocklists.domain_id and users.user_id=blocklists.user_id}}}{1} {yes}{no}}}}}}
11:30:25 91646    result: 
11:30:25 91646 ditch_hdrsubject router skipped: condition failure
11:30:25 91646 --------> virtual_vacation router <--------
11:30:25 91646 local_part=nick domain=domain.org.ua
11:30:25 91646 checking "condition"
11:30:25 91646 expanding: NULL
11:30:25 91646    result: NULL
11:30:25 91646 considering bool_lax: NULL
11:30:25 91646 expanding: $h_precedence:
11:30:25 91646    result: 
11:30:25 91646 expanding: (?i)junk|bulk|list
11:30:25 91646    result: (?i)junk|bulk|list
11:30:25 91646 expanding: $local_part
11:30:25 91646    result: nick
11:30:25 91646 expanding: $domain
11:30:25 91646    result: domain.org.ua
11:30:25 91646 expanding: select users.on_vacation from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_vacation = '1' and users.domain_id=domains.domain_id
11:30:25 91646    result: select users.on_vacation from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_vacation = '1' and users.domain_id=domains.domain_id
11:30:25 91646 search_open: mysql "NULL"
11:30:25 91646   cached open
11:30:25 91646 search_find: file="NULL"
11:30:25 91646   key="select users.on_vacation from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_vacation = '1' and users.domain_id=domains.domain_id" partial=-1 affix=NULL starflags=0
11:30:25 91646 LRU list:
11:30:25 91646 internal_search_find: file="NULL"
11:30:25 91646   type=mysql key="select users.on_vacation from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_vacation = '1' and users.domain_id=domains.domain_id"
11:30:25 91646 database lookup required for select users.on_vacation from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_vacation = '1' and users.domain_id=domains.domain_id
11:30:25 91646 MySQL query: select users.on_vacation from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_vacation = '1' and users.domain_id=domains.domain_id
11:30:25 91646 MYSQL using cached connection for localhost:(/tmp/mysql.sock)/vexim/vexim
11:30:25 91646 MYSQL: no data found
11:30:25 91646 lookup failed
11:30:25 91646 expanding: ${lookup mysql{select users.on_vacation from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_vacation = '1' and users.domain_id=domains.domain_id}}
11:30:25 91646    result: 
11:30:25 91646 expanding: 1
11:30:25 91646    result: 1
11:30:25 91646 condition: and { {!match {$h_precedence:}{(?i)junk|bulk|list}} {eq {${lookup mysql{select users.on_vacation from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_vacation = '1' and users.domain_id=domains.domain_id}}}{1} }}
11:30:25 91646    result: false
11:30:25 91646 expanding: yes
11:30:25 91646    result: yes
11:30:25 91646 skipping: result is not used
11:30:25 91646 expanding: no
11:30:25 91646    result: no
11:30:25 91646 expanding: ${if and { {!match {$h_precedence:}{(?i)junk|bulk|list}} {eq {${lookup mysql{select users.on_vacation from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_vacation = '1' and users.domain_id=domains.domain_id}}}{1} }} {yes}{no} }
11:30:25 91646    result: no
11:30:25 91646 considering bool_lax: no
11:30:25 91646 condition: and{{bool_lax{NULL}}{bool_lax{${if and { {!match {$h_precedence:}{(?i)junk|bulk|list}} {eq {${lookup mysql{select users.on_vacation from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_vacation = '1' and users.domain_id=domains.domain_id}}}{1} }} {yes}{no} }}}}
11:30:25 91646    result: false
11:30:25 91646 expanding: ${if and{{bool_lax{NULL}}{bool_lax{${if and { {!match {$h_precedence:}{(?i)junk|bulk|list}} {eq {${lookup mysql{select users.on_vacation from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_vacation = '1' and users.domain_id=domains.domain_id}}}{1} }} {yes}{no} }}}}}
11:30:25 91646    result: 
11:30:25 91646 virtual_vacation router skipped: condition failure
11:30:25 91646 --------> virtual_forward router <--------
11:30:25 91646 local_part=nick domain=domain.org.ua
11:30:25 91646 checking "condition"
11:30:25 91646 expanding: NULL
11:30:25 91646    result: NULL
11:30:25 91646 considering bool_lax: NULL
11:30:25 91646 expanding: $h_precedence:
11:30:25 91646    result: 
11:30:25 91646 expanding: (?i)junk
11:30:25 91646    result: (?i)junk
11:30:25 91646 expanding: $local_part
11:30:25 91646    result: nick
11:30:25 91646 expanding: $domain
11:30:25 91646    result: domain.org.ua
11:30:25 91646 expanding: select users.on_forward from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_forward = '1' and users.domain_id=domains.domain_id
11:30:25 91646    result: select users.on_forward from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_forward = '1' and users.domain_id=domains.domain_id
11:30:25 91646 search_open: mysql "NULL"
11:30:25 91646   cached open
11:30:25 91646 search_find: file="NULL"
11:30:25 91646   key="select users.on_forward from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_forward = '1' and users.domain_id=domains.domain_id" partial=-1 affix=NULL starflags=0
11:30:25 91646 LRU list:
11:30:25 91646 internal_search_find: file="NULL"
11:30:25 91646   type=mysql key="select users.on_forward from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_forward = '1' and users.domain_id=domains.domain_id"
11:30:25 91646 database lookup required for select users.on_forward from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_forward = '1' and users.domain_id=domains.domain_id
11:30:25 91646 MySQL query: select users.on_forward from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_forward = '1' and users.domain_id=domains.domain_id
11:30:25 91646 MYSQL using cached connection for localhost:(/tmp/mysql.sock)/vexim/vexim
11:30:25 91646 MYSQL: no data found
11:30:25 91646 lookup failed
11:30:25 91646 expanding: ${lookup mysql{select users.on_forward from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_forward = '1' and users.domain_id=domains.domain_id}}
11:30:25 91646    result: 
11:30:25 91646 expanding: 1
11:30:25 91646    result: 1
11:30:25 91646 condition: and { {!match {$h_precedence:}{(?i)junk}} {eq {${lookup mysql{select users.on_forward from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_forward = '1' and users.domain_id=domains.domain_id}}}{1} }}
11:30:25 91646    result: false
11:30:25 91646 expanding: yes
11:30:25 91646    result: yes
11:30:25 91646 skipping: result is not used
11:30:25 91646 expanding: no
11:30:25 91646    result: no
11:30:25 91646 expanding: ${if and { {!match {$h_precedence:}{(?i)junk}} {eq {${lookup mysql{select users.on_forward from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_forward = '1' and users.domain_id=domains.domain_id}}}{1} }} {yes}{no} }
11:30:25 91646    result: no
11:30:25 91646 considering bool_lax: no
11:30:25 91646 condition: and{{bool_lax{NULL}}{bool_lax{${if and { {!match {$h_precedence:}{(?i)junk}} {eq {${lookup mysql{select users.on_forward from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_forward = '1' and users.domain_id=domains.domain_id}}}{1} }} {yes}{no} }}}}
11:30:25 91646    result: false
11:30:25 91646 expanding: ${if and{{bool_lax{NULL}}{bool_lax{${if and { {!match {$h_precedence:}{(?i)junk}} {eq {${lookup mysql{select users.on_forward from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_forward = '1' and users.domain_id=domains.domain_id}}}{1} }} {yes}{no} }}}}}
11:30:25 91646    result: 
11:30:25 91646 virtual_forward router skipped: condition failure
11:30:25 91646 --------> virtual_domains router <--------
11:30:25 91646 local_part=nick domain=domain.org.ua
11:30:25 91646 calling virtual_domains router
11:30:25 91646 rda_interpret (string): ${lookup mysql{select smtp from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and domains.enabled = '1' and users.enabled = '1' and users.domain_id = domains.domain_id}}
11:30:25 91646 expanding: $local_part
11:30:25 91646    result: nick
11:30:25 91646 expanding: $domain
11:30:25 91646    result: domain.org.ua
11:30:25 91646 expanding: select smtp from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and domains.enabled = '1' and users.enabled = '1' and users.domain_id = domains.domain_id
11:30:25 91646    result: select smtp from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and domains.enabled = '1' and users.enabled = '1' and users.domain_id = domains.domain_id
11:30:25 91646 search_open: mysql "NULL"
11:30:25 91646   cached open
11:30:25 91646 search_find: file="NULL"
11:30:25 91646   key="select smtp from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and domains.enabled = '1' and users.enabled = '1' and users.domain_id = domains.domain_id" partial=-1 affix=NULL starflags=0
11:30:25 91646 LRU list:
11:30:25 91646 internal_search_find: file="NULL"
11:30:25 91646   type=mysql key="select smtp from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and domains.enabled = '1' and users.enabled = '1' and users.domain_id = domains.domain_id"
11:30:25 91646 database lookup required for select smtp from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and domains.enabled = '1' and users.enabled = '1' and users.domain_id = domains.domain_id
11:30:25 91646 MySQL query: select smtp from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and domains.enabled = '1' and users.enabled = '1' and users.domain_id = domains.domain_id
11:30:25 91646 MYSQL using cached connection for localhost:(/tmp/mysql.sock)/vexim/vexim
11:30:25 91646 lookup yielded: /usr/local/mail/domain.org.ua/nick/Maildir
11:30:25 91646 expanding: ${lookup mysql{select smtp from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and domains.enabled = '1' and users.enabled = '1' and users.domain_id = domains.domain_id}}
11:30:25 91646    result: /usr/local/mail/domain.org.ua/nick/Maildir
11:30:25 91646 expanded: /usr/local/mail/domain.org.ua/nick/Maildir
11:30:25 91646 file is not a filter file
11:30:25 91646 parse_forward_list: /usr/local/mail/domain.org.ua/nick/Maildir
11:30:25 91646 extract item: /usr/local/mail/domain.org.ua/nick/Maildir
11:30:25 91646 expanding: $spam_score_int
11:30:25 91646    result: 
11:30:25 91646 empty string cast to zero for numerical comparison
11:30:25 91646 expanding: $local_part
11:30:25 91646    result: nick
11:30:25 91646 expanding: $domain
11:30:25 91646    result: domain.org.ua
11:30:25 91646 expanding: select users.sa_tag * 10 from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_spamassassin = '1' and users.domain_id=domains.domain_id 
11:30:25 91646    result: select users.sa_tag * 10 from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_spamassassin = '1' and users.domain_id=domains.domain_id 
11:30:25 91646 search_open: mysql "NULL"
11:30:25 91646   cached open
11:30:25 91646 search_find: file="NULL"
11:30:25 91646   key="select users.sa_tag * 10 from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_spamassassin = '1' and users.domain_id=domains.domain_id " partial=-1 affix=NULL starflags=0
11:30:25 91646 LRU list:
11:30:25 91646 internal_search_find: file="NULL"
11:30:25 91646   type=mysql key="select users.sa_tag * 10 from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_spamassassin = '1' and users.domain_id=domains.domain_id "
11:30:25 91646 database lookup required for select users.sa_tag * 10 from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_spamassassin = '1' and users.domain_id=domains.domain_id 
11:30:25 91646 MySQL query: select users.sa_tag * 10 from users,domains where localpart = 'nick' and domain = 'domain.org.ua' and users.on_spamassassin = '1' and users.domain_id=domains.domain_id 
11:30:25 91646 MYSQL using cached connection for localhost:(/tmp/mysql.sock)/vexim/vexim
11:30:25 91646 MYSQL: no data found
11:30:25 91646 lookup failed
11:30:25 91646 expanding: $value
11:30:25 91646    result: 
11:30:25 91646 skipping: result is not used
11:30:25 91646 failed to expand: ${lookup mysql{select users.sa_tag * 10 from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_spamassassin = '1' and users.domain_id=domains.domain_id }{$value}fail}} {X-Spam-Flag: YES\n}{} }
11:30:25 91646    error message: "lookup" failed and "fail" requested
11:30:25 91646 failure was forced
11:30:25 91646 failed to expand: ${if >{$spam_score_int}{${lookup mysql{select users.sa_tag * 10 from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_spamassassin = '1' and users.domain_id=domains.domain_id }{$value}fail}} {X-Spam-Flag: YES\n}{} }
11:30:25 91646    error message: "lookup" failed and "fail" requested
11:30:25 91646 failure was forced
11:30:25 91646 expanding: $spam_score_int
11:30:25 91646    result: 
11:30:25 91646 empty string cast to zero for numerical comparison
11:30:25 91646 expanding: 1
11:30:25 91646    result: 1
11:30:25 91646 expanding: $spam_score_int
11:30:25 91646    result: 
11:30:25 91646 skipping: result is not used
11:30:25 91646 failed to expand: select users.sa_tag * 10 from users,domains where local
11:30:25 91646    error message: missing } at end of string
11:30:25 91646 failed to expand: ${lookup mysql{select users.sa_tag * 10 from users,domains where local
11:30:25 91646    error message: missing } at end of string
11:30:25 91646 failed to expand: ${if or { { <{$spam_score_int}{1} } { <{$spam_score_int}{${lookup mysql{select users.sa_tag * 10 from users,domains where local
11:30:25 91646    error message: missing } at end of string inside "or{...}" condition
11:30:25 91646 virtual_domains router: defer for nick@domain.org.ua
11:30:25 91646   message: virtual_domains router failed to expand "${if or { { <{$spam_score_int}{1} } { <{$spam_score_int}{${lookup mysql{select users.sa_tag * 10 from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_spamassassin = 1 and users.domain_id=domains.domain_id}{$value}fail}} } { eq {0}{${lookup mysql{select users.sa_tag * 10 from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_spamassassin = 0 and users.domain_id=domains.domain_id}{$value}fail}}} } {X-Spam-Score:X-Spam-Report} }": missing } at end of string inside "or{...}" condition
11:30:25 91646 added retry item for R:nick@domain.org.ua: errno=-1 more_errno=0 flags=0
11:30:25 91646 post-process nick@domain.org.ua (1)
11:30:25 91646 LOG: MAIN
11:30:25 91646   == nick@domain.org.ua R=virtual_domains defer (-1): Temporary internal error
11:30:25 91646 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
11:30:25 91646 After routing:
11:30:25 91646   Local deliveries:
11:30:25 91646   Remote deliveries:
11:30:25 91646   Failed addresses:
11:30:25 91646   Deferred addresses:
11:30:25 91646     nick@domain.org.ua
11:30:25 91646 search_tidyup called
11:30:25 91646 close MYSQL connection: localhost:(/tmp/mysql.sock)/vexim/vexim
11:30:25 91646 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
11:30:25 91646 changed uid/gid: post-delivery tidying
11:30:25 91646   uid=26 gid=6 pid=91646
11:30:25 91646   auxiliary group list: 6
11:30:25 91646 set_process_info: 91646 tidying up after delivering 1XMBNP-000NjE-9v
11:30:25 91646 Processing retry items
11:30:25 91646 Succeeded addresses:
11:30:25 91646 Failed addresses:
11:30:25 91646 Deferred addresses:
11:30:25 91646 nick@domain.org.ua
11:30:25 91646 locking /var/spool/exim/db/retry.lockfile
11:30:25 91646 locked /var/spool/exim/db/retry.lockfile
11:30:25 91646 EXIM_DBOPEN(/var/spool/exim/db/retry)
11:30:25 91646 returned from EXIM_DBOPEN
11:30:25 91646 opened hints database /var/spool/exim/db/retry: flags=O_RDWR
11:30:25 91646 address match test: subject=nick@domain.org.ua pattern=*
11:30:25 91646 domain.org.ua in "*"? yes (matched "*")
11:30:25 91646 nick@domain.org.ua in "*"? yes (matched "*")
11:30:25 91646 retry for R:nick@domain.org.ua = * 0 0
11:30:25 91646 dbfn_read: key=R:nick@domain.org.ua
11:30:25 91646 failing_interval=2954 message_age=2954
11:30:25 91646 Writing retry data for R:nick@domain.org.ua
11:30:25 91646   first failed=1409038871 last try=1409041825 next try=1409042725 expired=0
11:30:25 91646   errno=-1 more_errno=0 Temporary internal error
11:30:25 91646 dbfn_write: key=R:nick@domain.org.ua
11:30:25 91646 end of retry processing
11:30:25 91646 expanding: $h_list-id:$h_list-post:$h_list-subscribe:
11:30:25 91646    result: 
11:30:25 91646 expanding: 
11:30:25 91646    result: 
11:30:25 91646 expanding: $h_precedence:
11:30:25 91646    result: 
11:30:25 91646 expanding: (?i)bulk|list|junk
11:30:25 91646    result: (?i)bulk|list|junk
11:30:25 91646 expanding: $h_auto-submitted:
11:30:25 91646    result: 
11:30:25 91646 expanding: (?i)auto-generated|auto-replied
11:30:25 91646    result: (?i)auto-generated|auto-replied
11:30:25 91646 condition: or {{ !eq{$h_list-id:$h_list-post:$h_list-subscribe:}{} }{ match{$h_precedence:}{(?i)bulk|list|junk} }{ match{$h_auto-submitted:}{(?i)auto-generated|auto-replied} }}
11:30:25 91646    result: false
11:30:25 91646 expanding: no
11:30:25 91646    result: no
11:30:25 91646 skipping: result is not used
11:30:25 91646 expanding: yes
11:30:25 91646    result: yes
11:30:25 91646 expanding: ${if or {{ !eq{$h_list-id:$h_list-post:$h_list-subscribe:}{} }{ match{$h_precedence:}{(?i)bulk|list|junk} }{ match{$h_auto-submitted:}{(?i)auto-generated|auto-replied} }} {no}{yes}}
11:30:25 91646    result: yes
11:30:25 91646 time on queue = 49m14s
11:30:25 91646 warning counts: required 0 done 0
11:30:25 91646 delivery deferred: update_spool=0 header_rewritten=0
11:30:25 91646 end delivery of 1XMBNP-000NjE-9v
11:30:25 91646 search_tidyup called
11:30:25 91646 search_tidyup called
11:30:25 91646 >>>>>>>>>>>>>>>> Exim pid=91646 terminating with rc=0 >>>>>>>>>>>>>>>>
видим что проблема где-то с мускулом

Код: Выделить всё

11:30:25 91646 MySQL query: SELECT DISTINCT alias FROM domainalias WHERE alias = 'domain.org.ua'
11:30:25 91646 MYSQL using cached connection for localhost:(/tmp/mysql.sock)/vexim/vexim
11:30:25 91646 MYSQL: no data found
но лог не дает посыла на то что конкретно ему не дает нормально работать, в инете поискал, ответа не нашел, сам догнать что к чему не могу подскажите плз. Если делать откат на версию 4.82.1_7 то все работает нормально, только обновляюсь все перестает работать.

Хостинговая компания Host-Food.ru
Хостинг HostFood.ru
 

Услуги хостинговой компании Host-Food.ru

Хостинг HostFood.ru

Тарифы на хостинг в России, от 12 рублей: https://www.host-food.ru/tariffs/hosting/
Тарифы на виртуальные сервера (VPS/VDS/KVM) в РФ, от 189 руб.: https://www.host-food.ru/tariffs/virtualny-server-vps/
Выделенные сервера, Россия, Москва, от 2000 рублей (HP Proliant G5, Intel Xeon E5430 (2.66GHz, Quad-Core, 12Mb), 8Gb RAM, 2x300Gb SAS HDD, P400i, 512Mb, BBU):
https://www.host-food.ru/tariffs/vydelennyi-server-ds/
Недорогие домены в популярных зонах: https://www.host-food.ru/domains/

Аватара пользователя
Alex Keda
стреляли...
Сообщения: 35411
Зарегистрирован: 2004-10-18 14:25:19
Откуда: Made in USSR
Контактная информация:

Re: ПРоблема с Exim после обновления

Непрочитанное сообщение Alex Keda » 2014-08-31 9:49:20

Код: Выделить всё

11:30:25 91646   message: virtual_domains router failed to expand "${if or { { <{$spam_score_int}{1} } { <{$spam_score_int}{${lookup mysql{select users.sa_tag * 10 from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_spamassassin = 1 and users.domain_id=domains.domain_id}{$value}fail}} } { eq {0}{${lookup mysql{select users.sa_tag * 10 from users,domains where localpart = '${quote_mysql:$local_part}' and domain = '${quote_mysql:$domain}' and users.on_spamassassin = 0 and users.domain_id=domains.domain_id}{$value}fail}}} } {X-Spam-Score:X-Spam-Report} }": missing } at end of string inside "or{...}" condition
ошибка синтаксиса, насколько я понимаю
Убей их всех! Бог потом рассортирует...