Re: [Exim] headers_add not working

Top Page
Delete this message
Reply to this message
Author: David Saez
Date:  
To: Philip Hazel
CC: exim-users
Subject: Re: [Exim] headers_add not working
This is a multi-part message in MIME format.
--
Hi !!

> So, in order to proceed with this, I'll need to see debug output from a
> case where this fails, because I haven't been able to create such a case
> for myself.


Here I send the debug output plus the resulting message without the
headers being added. Router is named virtual_aliases router and headers to
add is X-Forward, which is correctly expanded but not added to the
resulting message (resulting message also included)

--
Thanx & best regards ...

Reality is nothing but a collective hunch.

----------------------------------------------------------------
   David Saez Padros                http://www.ols.es
   On-Line Services 2000 S.L.       e-mail  david@???
   Pintor Vayreda 1                 telf    +34 902 50 29 75
   08184 Palau-Solita i Plegamans   movil   +34 670 35 27 53
----------------------------------------------------------------
--
# echo "Test" | /usr/local/exim/bin/exim -d+expand -f david@??? ols2000@???


Exim version 4.24 uid=0 gid=0 pid=7491 D=fbb95dfd
Berkeley DB: Sleepycat Software: Berkeley DB 3.1.17: (July 31, 2000)
Support for: iconv() OpenSSL
Authenticators: cram_md5 plaintext spa
Routers: accept dnslookup ipliteral manualroute queryprogram redirect
Transports: appendfile/maildir autoreply pipe smtp
changed uid/gid: forcing real = effective
uid=0 gid=0 pid=7491
auxiliary group list: <none>
configuration file is /usr/local/exim/configure
log selector = 040f19f8
trusted user
admin user
changed uid/gid: privilege not needed
uid=1006 gid=101 pid=7491
auxiliary group list: <none>
finduser used cached passwd data for exim
finduser used cached passwd data for exim
finduser used cached passwd data for exim
finduser used cached passwd data for exim
finduser used cached passwd data for exim
finduser used cached passwd data for exim
originator: uid=0 gid=0 login=root name=
sender address = david@???
set_process_info: 7491 accepting a local non-SMTP message from <david@???>
Sender: david@???
Recipients:
ols2000@???
search_tidyup called
>>Headers received:


expanding: ${tod_full}
result: Fri, 07 Nov 2003 17:42:23 +0100
condition: def:sender_rcvhost
result: false
expanding: from $sender_rcvhost

result: from

skipping: result is not used
condition: def:sender_ident
result: true
expanding: from $sender_ident
result: from root
condition: def:sender_helo_name
result: false
expanding: (helo=$sender_helo_name)

result: (helo=)

skipping: result is not used
expanding: ${if def:sender_ident {from $sender_ident }}${if def:sender_helo_name {(helo=$sender_helo_name)
        }}
   result: from root
condition: def:received_protocol
   result: true
expanding: with $received_protocol
   result: with local
condition: def:tls_cipher
   result: false
expanding: ($tls_cipher)


result: ()

skipping: result is not used
condition: def:received_for
   result: true
expanding:
        for $received_for
   result:
        for ols2000@???
expanding: Received: ${if def:sender_rcvhost {from $sender_rcvhost
        }{${if def:sender_ident {from $sender_ident }}${if def:sender_helo_name {(helo=$sender_helo_name)
        }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol}} ${if def:tls_cipher {($tls_cipher)
        }}(Exim $version_number)
        id $message_id${if def:received_for {
        for $received_for}}
   result: Received: from root by a.mx.ols.es with local (Exim 4.24)
        id 1AI9gl-0001wp-TN
        for ols2000@???
address match: subject=david@??? pattern=apache@???
david@??? in "apache@???"? no (end of list)
rewritten sender = david@???
rewrite_one_header: type=F:
  From: david@???
search_tidyup called

>>Headers after local additions:

P Received: from root by a.mx.ols.es with local (Exim 4.24)
        id 1AI9gl-0001wp-TN
        for ols2000@???; Fri, 07 Nov 2003 17:42:23 +0100
I Message-Id: <E1AI9gl-0001wp-TN@???>
F From: david@???
B Bcc:
  Date: Fri, 07 Nov 2003 17:42:23 +0100


Data file written for message 1AI9gl-0001wp-TN
calling local_scan(); timeout=300
local_scan() returned 0 NULL
Writing spool header file
Size of headers = 238
LOG: MAIN
<= david@??? U=root P=local S=244
search_tidyup called
exec /usr/local/exim/bin/exim -d=0xfbb95dfd -Mc 1AI9gl-0001wp-TN
Exim version 4.24 uid=1006 gid=101 pid=7492 D=fbb95dfd
Berkeley DB: Sleepycat Software: Berkeley DB 3.1.17: (July 31, 2000)
Support for: iconv() OpenSSL
Authenticators: cram_md5 plaintext spa
Routers: accept dnslookup ipliteral manualroute queryprogram redirect
Transports: appendfile/maildir autoreply pipe smtp
changed uid/gid: forcing real = effective
uid=0 gid=101 pid=7492
auxiliary group list: <none>
configuration file is /usr/local/exim/configure
log selector = 040f19f8
trusted user
admin user
skipping ACL configuration - not needed
finduser used cached passwd data for exim
>>>>>>>>>>>>>>>> Exim pid=7491 terminating with rc=0 >>>>>>>>>>>>>>>>

finduser used cached passwd data for exim
finduser used cached passwd data for exim
finduser used cached passwd data for exim
finduser used cached passwd data for exim
finduser used cached passwd data for exim
set_process_info: 7492 delivering specified messages
set_process_info: 7492 delivering 1AI9gl-0001wp-TN
reading spool file 1AI9gl-0001wp-TN-H
user=root uid=0 gid=0 sender=david@???
sender_local=0 ident=root
Non-recipients:
Empty Tree
---- End of tree ----
recipients_count=1
body_linecount=1 message_linecount=7
Delivery address list:
ols2000@???
locking /var/spool/exim/db/retry.lockfile
locked /var/spool/exim/db/retry.lockfile
opened hints database /var/spool/exim/db/retry: flags=0
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

Considering: ols2000@???
unique = ols2000@???
dbfn_read: key=R:ols.es
dbfn_read: key=R:ols2000@???
no domain retry record
no address retry record
ols2000@???: queued for routing
expanding: $domain
result: ols.es
expanding: ultra.ols.es:rackuk.ols.es:mysql;SELECT DISTINCT domain FROM servicios WHERE user='${quote_mysql:$domain}' AND type='dom'
result: ultra.ols.es:rackuk.ols.es:mysql;SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'
search_open: mysql "NULL"
search_find: file="NULL"
key="SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'" partial=-1 affix=NULL starflags=0
LRU list:
internal_search_find: file="NULL"
type=mysql key="SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'"
database lookup required for SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'
MYSQL query: SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'
MYSQL new connection: host=localhost port=0 socket=NULL database=online user=exim
lookup yielded: ols.es
ols.es in "ultra.ols.es:rackuk.ols.es:mysql;SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'"? yes (matched "mysql;SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'")
ols.es in queue_domains? no (matched "!+local_domains")
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

routing ols2000@???
--------> copymail router <--------
local_part=ols2000 domain=ols.es
processing address_data
expanding: $sender_address
result: david@???
expanding: $local_part
result: ols2000
expanding: $domain
result: ols.es
expanding: SELECT name FROM servicios WHERE type='cpy' AND (user='${quote_mysql:$sender_address}' OR user='${quote_mysql:$local_part}@${quote_mysql:$domain}')
result: SELECT name FROM servicios WHERE type='cpy' AND (user='david@???' OR user='ols2000@???')
search_open: mysql "NULL"
cached open
search_find: file="NULL"
key="SELECT name FROM servicios WHERE type='cpy' AND (user='david@???' OR user='ols2000@???')" partial=-1 affix=NULL starflags=0
LRU list:
internal_search_find: file="NULL"
type=mysql key="SELECT name FROM servicios WHERE type='cpy' AND (user='david@???' OR user='ols2000@???')"
database lookup required for SELECT name FROM servicios WHERE type='cpy' AND (user='david@???' OR user='ols2000@???')
MYSQL query: SELECT name FROM servicios WHERE type='cpy' AND (user='david@???' OR user='ols2000@???')
MYSQL using cached connection for localhost/online/exim
MYSQL: no data found
lookup failed
expanding: $value
result:
skipping: result is not used
failed to expand: ${lookup mysql {SELECT name FROM servicios WHERE type='cpy' AND (user='${quote_mysql:$sender_address}' OR user='${quote_mysql:$local_part}@${quote_mysql:$domain}')}{$value}fail}
error message: "lookup" failed and "fail" requested
failure was forced
forced failure in expansion of "${lookup mysql {SELECT name FROM servicios WHERE type='cpy' AND (user='${quote_mysql:$sender_address}' OR user='${quote_mysql:$local_part}@${quote_mysql:$domain}')}{$value}fail}" (address_data): decline action taken
--------> dnslookup router <--------
local_part=ols2000 domain=ols.es
checking domains
expanding: $domain
result: ols.es
expanding: ultra.ols.es:rackuk.ols.es:mysql;SELECT DISTINCT domain FROM servicios WHERE user='${quote_mysql:$domain}' AND type='dom'
result: ultra.ols.es:rackuk.ols.es:mysql;SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'
search_open: mysql "NULL"
cached open
search_find: file="NULL"
key="SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'" partial=-1 affix=NULL starflags=0
LRU list:
internal_search_find: file="NULL"
type=mysql key="SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'"
database lookup required for SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'
MYSQL query: SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'
MYSQL using cached connection for localhost/online/exim
lookup yielded: ols.es
ols.es in "ultra.ols.es:rackuk.ols.es:mysql;SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'"? yes (matched "mysql;SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'")
ols.es in "! +local_domains"? no (matched "! +local_domains")
dnslookup router skipped: domains mismatch
--------> spam_director router <--------
local_part=ols2000 domain=ols.es
calling spam_director router
spam_director router called for ols2000@???
domain = ols.es
set transport spam_transport
queued for spam_transport transport: local_part = ols2000
domain = ols.es
errors_to=NULL
domain_data=NULL localpart_data=NULL
routed by spam_director router (unseen)
envelope to: ols2000@???
transport: spam_transport
"unseen" set: replicated ols2000@???
locking /var/spool/exim/db/retry.lockfile
locked /var/spool/exim/db/retry.lockfile
opened hints database /var/spool/exim/db/retry: flags=0
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

Considering: ols2000@???
unique = \0\ols2000@???
dbfn_read: key=R:ols.es
dbfn_read: key=R:ols2000@???
no domain retry record
no address retry record
ols2000@???: queued for routing
expanding: $domain
result: ols.es
expanding: ultra.ols.es:rackuk.ols.es:mysql;SELECT DISTINCT domain FROM servicios WHERE user='${quote_mysql:$domain}' AND type='dom'
result: ultra.ols.es:rackuk.ols.es:mysql;SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'
search_open: mysql "NULL"
cached open
search_find: file="NULL"
key="SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'" partial=-1 affix=NULL starflags=0
LRU list:
internal_search_find: file="NULL"
type=mysql key="SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'"
cached data used for lookup of SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'
lookup yielded: ols.es
ols.es in "ultra.ols.es:rackuk.ols.es:mysql;SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'"? yes (matched "mysql;SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'")
ols.es in queue_domains? no (matched "!+local_domains")
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

routing ols2000@???
--------> system_aliases router <--------
local_part=ols2000 domain=ols.es
calling system_aliases router
rda_interpret (string): ${lookup{$local_part}lsearch{/usr/local/exim/aliases}}
expanding: $local_part
result: ols2000
expanding: /usr/local/exim/aliases
result: /usr/local/exim/aliases
search_open: lsearch "/usr/local/exim/aliases"
search_find: file="/usr/local/exim/aliases"
key="ols2000" partial=-1 affix=NULL starflags=0
LRU list:
9/usr/local/exim/aliases
End
internal_search_find: file="/usr/local/exim/aliases"
type=lsearch key="ols2000"
file lookup required for ols2000
in /usr/local/exim/aliases
lookup failed
expanding: ${lookup{$local_part}lsearch{/usr/local/exim/aliases}}
result:
expanded:
file is not a filter file
parse_forward_list:
system_aliases router declined for ols2000@???
--------> virtual_aliases router <--------
local_part=ols2000 domain=ols.es
calling virtual_aliases router
rda_interpret (string): ${lookup mysql {SELECT name FROM servicios WHERE user='${quote_mysql:$local_part}@${quote_mysql:$domain}' AND type='ali'}}
expanding: $local_part
result: ols2000
expanding: $domain
result: ols.es
expanding: SELECT name FROM servicios WHERE user='${quote_mysql:$local_part}@${quote_mysql:$domain}' AND type='ali'
result: SELECT name FROM servicios WHERE user='ols2000@???' AND type='ali'
search_open: mysql "NULL"
cached open
search_find: file="NULL"
key="SELECT name FROM servicios WHERE user='ols2000@???' AND type='ali'" partial=-1 affix=NULL starflags=0
LRU list:
9/usr/local/exim/aliases
End
internal_search_find: file="NULL"
type=mysql key="SELECT name FROM servicios WHERE user='ols2000@???' AND type='ali'"
database lookup required for SELECT name FROM servicios WHERE user='ols2000@???' AND type='ali'
MYSQL query: SELECT name FROM servicios WHERE user='ols2000@???' AND type='ali'
MYSQL using cached connection for localhost/online/exim
lookup yielded: david@???
expanding: ${lookup mysql {SELECT name FROM servicios WHERE user='${quote_mysql:$local_part}@${quote_mysql:$domain}' AND type='ali'}}
result: david@???
expanded: david@???
file is not a filter file
parse_forward_list: david@???
extract item: david@???
expanding: X-Forward: for $local_part@$domain by $sender_address at $primary_hostname
result: X-Forward: for ols2000@??? by david@??? at a.mx.ols.es
virtual_aliases router generated david@???
errors_to=NULL transport=NULL
uid=unset gid=unset home=NULL
routed by virtual_aliases router
envelope to: ols2000@???
transport: <none>
locking /var/spool/exim/db/retry.lockfile
locked /var/spool/exim/db/retry.lockfile
opened hints database /var/spool/exim/db/retry: flags=0
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

Considering: david@???
unique = david@???
dbfn_read: key=R:ols.es
dbfn_read: key=R:david@???
no domain retry record
no address retry record
david@???: queued for routing
expanding: $domain
result: ols.es
expanding: ultra.ols.es:rackuk.ols.es:mysql;SELECT DISTINCT domain FROM servicios WHERE user='${quote_mysql:$domain}' AND type='dom'
result: ultra.ols.es:rackuk.ols.es:mysql;SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'
search_open: mysql "NULL"
cached open
search_find: file="NULL"
key="SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'" partial=-1 affix=NULL starflags=0
LRU list:
9/usr/local/exim/aliases
End
internal_search_find: file="NULL"
type=mysql key="SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'"
database lookup required for SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'
MYSQL query: SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'
MYSQL using cached connection for localhost/online/exim
lookup yielded: ols.es
ols.es in "ultra.ols.es:rackuk.ols.es:mysql;SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'"? yes (matched "mysql;SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'")
ols.es in queue_domains? no (matched "!+local_domains")
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

routing david@???
--------> copymail router <--------
local_part=david domain=ols.es
processing address_data
expanding: $sender_address
result: david@???
expanding: $local_part
result: david
expanding: $domain
result: ols.es
expanding: SELECT name FROM servicios WHERE type='cpy' AND (user='${quote_mysql:$sender_address}' OR user='${quote_mysql:$local_part}@${quote_mysql:$domain}')
result: SELECT name FROM servicios WHERE type='cpy' AND (user='david@???' OR user='david@???')
search_open: mysql "NULL"
cached open
search_find: file="NULL"
key="SELECT name FROM servicios WHERE type='cpy' AND (user='david@???' OR user='david@???')" partial=-1 affix=NULL starflags=0
LRU list:
9/usr/local/exim/aliases
End
internal_search_find: file="NULL"
type=mysql key="SELECT name FROM servicios WHERE type='cpy' AND (user='david@???' OR user='david@???')"
database lookup required for SELECT name FROM servicios WHERE type='cpy' AND (user='david@???' OR user='david@???')
MYSQL query: SELECT name FROM servicios WHERE type='cpy' AND (user='david@???' OR user='david@???')
MYSQL using cached connection for localhost/online/exim
MYSQL: no data found
lookup failed
expanding: $value
result:
skipping: result is not used
failed to expand: ${lookup mysql {SELECT name FROM servicios WHERE type='cpy' AND (user='${quote_mysql:$sender_address}' OR user='${quote_mysql:$local_part}@${quote_mysql:$domain}')}{$value}fail}
error message: "lookup" failed and "fail" requested
failure was forced
forced failure in expansion of "${lookup mysql {SELECT name FROM servicios WHERE type='cpy' AND (user='${quote_mysql:$sender_address}' OR user='${quote_mysql:$local_part}@${quote_mysql:$domain}')}{$value}fail}" (address_data): decline action taken
--------> dnslookup router <--------
local_part=david domain=ols.es
checking domains
expanding: $domain
result: ols.es
expanding: ultra.ols.es:rackuk.ols.es:mysql;SELECT DISTINCT domain FROM servicios WHERE user='${quote_mysql:$domain}' AND type='dom'
result: ultra.ols.es:rackuk.ols.es:mysql;SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'
search_open: mysql "NULL"
cached open
search_find: file="NULL"
key="SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'" partial=-1 affix=NULL starflags=0
LRU list:
9/usr/local/exim/aliases
End
internal_search_find: file="NULL"
type=mysql key="SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'"
database lookup required for SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'
MYSQL query: SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'
MYSQL using cached connection for localhost/online/exim
lookup yielded: ols.es
ols.es in "ultra.ols.es:rackuk.ols.es:mysql;SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'"? yes (matched "mysql;SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'")
ols.es in "! +local_domains"? no (matched "! +local_domains")
dnslookup router skipped: domains mismatch
--------> spam_director router <--------
local_part=david domain=ols.es
calling spam_director router
spam_director router called for david@???
domain = ols.es
queued for spam_transport transport: local_part = david
domain = ols.es
errors_to=NULL
domain_data=NULL localpart_data=NULL
routed by spam_director router (unseen)
envelope to: david@???
transport: spam_transport
"unseen" set: replicated david@???
locking /var/spool/exim/db/retry.lockfile
locked /var/spool/exim/db/retry.lockfile
opened hints database /var/spool/exim/db/retry: flags=0
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

Considering: david@???
unique = \0\david@???
dbfn_read: key=R:ols.es
dbfn_read: key=R:david@???
no domain retry record
no address retry record
david@???: queued for routing
expanding: $domain
result: ols.es
expanding: ultra.ols.es:rackuk.ols.es:mysql;SELECT DISTINCT domain FROM servicios WHERE user='${quote_mysql:$domain}' AND type='dom'
result: ultra.ols.es:rackuk.ols.es:mysql;SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'
search_open: mysql "NULL"
cached open
search_find: file="NULL"
key="SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'" partial=-1 affix=NULL starflags=0
LRU list:
9/usr/local/exim/aliases
End
internal_search_find: file="NULL"
type=mysql key="SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'"
cached data used for lookup of SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'
lookup yielded: ols.es
ols.es in "ultra.ols.es:rackuk.ols.es:mysql;SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'"? yes (matched "mysql;SELECT DISTINCT domain FROM servicios WHERE user='ols.es' AND type='dom'")
ols.es in queue_domains? no (matched "!+local_domains")
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

routing david@???
--------> system_aliases router <--------
local_part=david domain=ols.es
calling system_aliases router
rda_interpret (string): ${lookup{$local_part}lsearch{/usr/local/exim/aliases}}
expanding: $local_part
result: david
expanding: /usr/local/exim/aliases
result: /usr/local/exim/aliases
search_open: lsearch "/usr/local/exim/aliases"
cached open
search_find: file="/usr/local/exim/aliases"
key="david" partial=-1 affix=NULL starflags=0
LRU list:
9/usr/local/exim/aliases
End
internal_search_find: file="/usr/local/exim/aliases"
type=lsearch key="david"
file lookup required for david
in /usr/local/exim/aliases
lookup failed
expanding: ${lookup{$local_part}lsearch{/usr/local/exim/aliases}}
result:
expanded:
file is not a filter file
parse_forward_list:
system_aliases router declined for david@???
--------> virtual_aliases router <--------
local_part=david domain=ols.es
calling virtual_aliases router
rda_interpret (string): ${lookup mysql {SELECT name FROM servicios WHERE user='${quote_mysql:$local_part}@${quote_mysql:$domain}' AND type='ali'}}
expanding: $local_part
result: david
expanding: $domain
result: ols.es
expanding: SELECT name FROM servicios WHERE user='${quote_mysql:$local_part}@${quote_mysql:$domain}' AND type='ali'
result: SELECT name FROM servicios WHERE user='david@???' AND type='ali'
search_open: mysql "NULL"
cached open
search_find: file="NULL"
key="SELECT name FROM servicios WHERE user='david@???' AND type='ali'" partial=-1 affix=NULL starflags=0
LRU list:
9/usr/local/exim/aliases
End
internal_search_find: file="NULL"
type=mysql key="SELECT name FROM servicios WHERE user='david@???' AND type='ali'"
database lookup required for SELECT name FROM servicios WHERE user='david@???' AND type='ali'
MYSQL query: SELECT name FROM servicios WHERE user='david@???' AND type='ali'
MYSQL using cached connection for localhost/online/exim
MYSQL: no data found
lookup failed
expanding: ${lookup mysql {SELECT name FROM servicios WHERE user='${quote_mysql:$local_part}@${quote_mysql:$domain}' AND type='ali'}}
result:
expanded:
file is not a filter file
parse_forward_list:
virtual_aliases router declined for david@???
--------> user_filter router <--------
local_part=david domain=ols.es
calling user_filter router
rda_interpret (string): ${lookup mysql {SELECT filter FROM filtros WHERE email='${quote_mysql:$local_part}@${quote_mysql:$domain}'}}
expanding: $local_part
result: david
expanding: $domain
result: ols.es
expanding: SELECT filter FROM filtros WHERE email='${quote_mysql:$local_part}@${quote_mysql:$domain}'
result: SELECT filter FROM filtros WHERE email='david@???'
search_open: mysql "NULL"
cached open
search_find: file="NULL"
key="SELECT filter FROM filtros WHERE email='david@???'" partial=-1 affix=NULL starflags=0
LRU list:
9/usr/local/exim/aliases
End
internal_search_find: file="NULL"
type=mysql key="SELECT filter FROM filtros WHERE email='david@???'"
database lookup required for SELECT filter FROM filtros WHERE email='david@???'
MYSQL query: SELECT filter FROM filtros WHERE email='david@???'
MYSQL using cached connection for localhost/online/exim
lookup yielded: # Exim filter
#
#if $header_precedence: does not contain "bulk" and
# $header_precedence: does not contain "list" and
# $header_precedence: does not contain "junk" and
# (${lc:$h_subject:} matches "(content violation|content security|virus|wirus|infectado|viirus)" or
# ${lc:$h_subject:} matches "(groupshield|scanmail|mcafee|antivirus|kaspersk|webshield|esafe)" or
# ${lc:$h_subject:} matches "(screensaver|application|interscan|violation|resultado do scan)" or
# ${lc:$h_subject:} matches "(infected|mimedefang notification|re: movie|uwaga|wirusa)" or
# ${lc:$h_subject:} matches "(bloqued|attachment|warning|warnung|unsafe|sobig)" or
# ${lc:$h_subject:} matches "(blocked|approved)" or
# $h_X-Auto-Generated: contains "McAfee antivirus plugin" or
# ${lc:$message_body} matches "(trend gatelock|rav antivirus|virus warning message)" or
# ${lc:$message_body} matches "(mdaemon antivirus|scanned by antivirus|antivirus results)" or
#
**** debug string too long - truncated ****
expanding: ${lookup mysql {SELECT filter FROM filtros WHERE email='${quote_mysql:$local_part}@${quote_mysql:$domain}'}}
result: # Exim filter
#
#if $header_precedence: does not contain "bulk" and
# $header_precedence: does not contain "list" and
# $header_precedence: does not contain "junk" and
# (${lc:$h_subject:} matches "(content violation|content security|virus|wirus|infectado|viirus)" or
# ${lc:$h_subject:} matches "(groupshield|scanmail|mcafee|antivirus|kaspersk|webshield|esafe)" or
# ${lc:$h_subject:} matches "(screensaver|application|interscan|violation|resultado do scan)" or
# ${lc:$h_subject:} matches "(infected|mimedefang notification|re: movie|uwaga|wirusa)" or
# ${lc:$h_subject:} matches "(bloqued|attachment|warning|warnung|unsafe|sobig)" or
# ${lc:$h_subject:} matches "(blocked|approved)" or
# $h_X-Auto-Generated: contains "McAfee antivirus plugin" or
# ${lc:$message_body} matches "(trend gatelock|rav antivirus|virus warni
**** debug string too long - truncated ****
expanded: # Exim filter
#
#if $header_precedence: does not contain "bulk" and
# $header_precedence: does not contain "list" and
# $header_precedence: does not contain "junk" and
# (${lc:$h_subject:} matches "(content violation|content security|virus|wirus|infectado|viirus)" or
# ${lc:$h_subject:} matches "(groupshield|scanmail|mcafee|antivirus|kaspersk|webshield|esafe)" or
# ${lc:$h_subject:} matches "(screensaver|application|interscan|violation|resultado do scan)" or
# ${lc:$h_subject:} matches "(infected|mimedefang notification|re: movie|uwaga|wirusa)" or
# ${lc:$h_subject:} matches "(bloqued|attachment|warning|warnung|unsafe|sobig)" or
# ${lc:$h_subject:} matches "(blocked|approved)" or
# $h_X-Auto-Generated: contains "McAfee antivirus plugin" or
# ${lc:$message_body} matches "(trend gatelock|rav antivirus|virus warning message)" or
# ${lc:$message_body} matches "(mdaemon antivirus|scanned by antivirus|antivirus results)" or
# ${lc:
**** debug string too long - truncated ****
changed uid/gid: user_filter router (recipient is david@???)
uid=1006 gid=1006 pid=7500
auxiliary group list: <none>
data is an Exim filter program
Filter: start of processing
Filter: end of processing
rda_interpret: subprocess yield=1 error=NULL
user_filter router declined for david@???
--------> virtual_local_user router <--------
local_part=david domain=ols.es
checking domains
expanding: $local_part
result: david
expanding: $domain
result: ols.es
expanding: SELECT DISTINCT domain FROM servicios WHERE name='${quote_mysql:$local_part}@${quote_mysql:$domain}' AND type='pop'
result: SELECT DISTINCT domain FROM servicios WHERE name='david@???' AND type='pop'
search_open: mysql "NULL"
cached open
search_find: file="NULL"
key="SELECT DISTINCT domain FROM servicios WHERE name='david@???' AND type='pop'" partial=-1 affix=NULL starflags=0
LRU list:
9/usr/local/exim/aliases
End
internal_search_find: file="NULL"
type=mysql key="SELECT DISTINCT domain FROM servicios WHERE name='david@???' AND type='pop'"
database lookup required for SELECT DISTINCT domain FROM servicios WHERE name='david@???' AND type='pop'
MYSQL query: SELECT DISTINCT domain FROM servicios WHERE name='david@???' AND type='pop'
MYSQL using cached connection for localhost/online/exim
lookup yielded: ols.es
expanding: $value
result: ols.es
expanding: ${lookup mysql {SELECT DISTINCT domain FROM servicios WHERE name='${quote_mysql:$local_part}@${quote_mysql:$domain}' AND type='pop'}{$value}}
result: ols.es
ols.es in "ols.es"? yes (matched "ols.es")
calling virtual_local_user router
virtual_local_user router called for david@???
domain = ols.es
set transport virtual_delivery
queued for virtual_delivery transport: local_part = david
domain = ols.es
errors_to=NULL
domain_data=NULL localpart_data=NULL
routed by virtual_local_user router
envelope to: david@???
transport: virtual_delivery
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

After routing:
  Local deliveries:
    david@???
    david@???
    ols2000@???
  Remote deliveries:
  Failed addresses:
  Deferred addresses:
search_tidyup called
close MYSQL connection: localhost/online/exim

>>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>

--------> david@??? <--------
locking /var/spool/exim/db/retry.lockfile
locked /var/spool/exim/db/retry.lockfile
opened hints database /var/spool/exim/db/retry: flags=0
dbfn_read: key=T:david@???
no retry record exists
search_tidyup called
changed uid/gid: local delivery to david <david@???> transport=virtual_delivery
uid=1006 gid=12 pid=7501
auxiliary group list: <none>
home=NULL current=/
set_process_info: 7501 delivering 1AI9gl-0001wp-TN to david using virtual_delivery
appendfile transport entered
expanding: /var/spool/exim/mail/${domain}/${local_part}
result: /var/spool/exim/mail/ols.es/david
appendfile: mode=660 notify_comsat=0 quota=0
directory=/var/spool/exim/mail/ols.es/david format=maildir
message_prefix=
message_suffix=
delivering in maildir format in /var/spool/exim/mail/ols.es/david
writing to file tmp/1068223347.H410456P7501.a.mx.ols.es
writing data block fd=5 size=347 timeout=0
renaming temporary file
renamed tmp/1068223347.H410456P7501.a.mx.ols.es as new/1068223347.H410456P7501.a.mx.ols.es
appendfile yields 0 with errno=0 more_errno=0
search_tidyup called
journalling david@???/virtual_delivery
virtual_delivery transport returned OK for david@???
post-process david@??? (0)
david@??? delivered
LOG: MAIN
=> david <ols2000@???> F=<david@???> R=virtual_local_user T=virtual_delivery S=347
--------> david@??? <--------
locking /var/spool/exim/db/retry.lockfile
locked /var/spool/exim/db/retry.lockfile
opened hints database /var/spool/exim/db/retry: flags=0
dbfn_read: key=T:david@???
no retry record exists
search_tidyup called
changed uid/gid: local delivery to david <david@???> transport=spam_transport
uid=1006 gid=12 pid=7502
auxiliary group list: <none>
home=NULL current=/
set_process_info: 7502 delivering 1AI9gl-0001wp-TN to david using spam_transport
appendfile transport entered
appendfile: mode=660 notify_comsat=0 quota=0
directory=/var/spool/exim/spam format=maildir
message_prefix=null
message_suffix=null
delivering in maildir format in /var/spool/exim/spam
writing to file tmp/1068223347.H509726P7502.a.mx.ols.es
added header line(s):
X-Forward: for ols2000@??? by david@??? at a.mx.ols.es
---
writing data block fd=6 size=408 timeout=0
renaming temporary file
renamed tmp/1068223347.H509726P7502.a.mx.ols.es as new/1068223347.H509726P7502.a.mx.ols.es
appendfile yields 0 with errno=0 more_errno=0
search_tidyup called
journalling david@???/spam_director
spam_transport transport returned OK for david@???
post-process david@??? (0)
david@??? delivered
david@???: children all complete
LOG: MAIN
=> david <ols2000@???> F=<david@???> R=spam_director T=spam_transport S=408
--------> ols2000@??? <--------
locking /var/spool/exim/db/retry.lockfile
locked /var/spool/exim/db/retry.lockfile
opened hints database /var/spool/exim/db/retry: flags=0
dbfn_read: key=T:ols2000@???
no retry record exists
search_tidyup called
changed uid/gid: local delivery to ols2000 <ols2000@???> transport=spam_transport
uid=1006 gid=12 pid=7503
auxiliary group list: <none>
home=NULL current=/
set_process_info: 7503 delivering 1AI9gl-0001wp-TN to ols2000 using spam_transport
appendfile transport entered
appendfile: mode=660 notify_comsat=0 quota=0
directory=/var/spool/exim/spam format=maildir
message_prefix=null
message_suffix=null
delivering in maildir format in /var/spool/exim/spam
writing to file tmp/1068223347.H525328P7503.a.mx.ols.es
writing data block fd=6 size=347 timeout=0
renaming temporary file
renamed tmp/1068223347.H525328P7503.a.mx.ols.es as new/1068223347.H525328P7503.a.mx.ols.es
appendfile yields 0 with errno=0 more_errno=0
search_tidyup called
journalling ols2000@???/spam_director
spam_transport transport returned OK for ols2000@???
post-process ols2000@??? (0)
ols2000@??? delivered
ols2000@???: children all complete
LOG: MAIN
=> ols2000 <ols2000@???> F=<david@???> R=spam_director T=spam_transport S=347
>>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>

changed uid/gid: post-delivery tidying
uid=1006 gid=101 pid=7492
auxiliary group list: <none>
set_process_info: 7492 tidying up after delivering 1AI9gl-0001wp-TN
Processing retry items
Succeeded addresses:
ols2000@???: no retry items
ols2000@???: no retry items
david@???: no retry items
david@???: no retry items
ols2000@???: no retry items
ols2000@???: no retry items
david@???: no retry items
david@???: no retry items
ols2000@???: no retry items
ols2000@???: no retry items
ols2000@???: no retry items
ols2000@???: no retry items
Failed addresses:
Deferred addresses:
end of retry processing
LOG: MAIN
Completed
end delivery of 1AI9gl-0001wp-TN
search_tidyup called
search_tidyup called
>>>>>>>>>>>>>>>> Exim pid=7492 terminating with rc=0 >>>>>>>>>>>>>>>>




#cat /var/spool/exim/mail/ols.es/david/new/1068223347.H410456P7501.a.mx.ols.es

Return-path: <david@???>
Envelope-to: ols2000@???
Delivery-date: Fri, 07 Nov 2003 17:42:27 +0100
Received: from root by a.mx.ols.es with local (Exim 4.24)
        id 1AI9gl-0001wp-TN
        for ols2000@???; Fri, 07 Nov 2003 17:42:23 +0100
Message-Id: <E1AI9gl-0001wp-TN@???>
From: david@???
Bcc:
Date: Fri, 07 Nov 2003 17:42:23 +0100


Test
--