Re: [exim] Strange system filter behaviour.

Top Page
Delete this message
Reply to this message
Author: Molly Fletcher
Date:  
To: Todd Lyons
CC: exim-users@exim.org, Phil Pennock, Ian Eiloart
Subject: Re: [exim] Strange system filter behaviour.
On 30/10/12 13:58, Molly Fletcher wrote:
> On 30/10/12 13:19, Todd Lyons wrote:
>> On Tue, Oct 30, 2012 at 3:57 AM, Molly Fletcher
>> <molly.fletcher@???> wrote:
>>>>>>> Has nobody ever used this before and noticed that email addresses
>>>>>>> still have this '>' character in front of them? Googling didn't find
>>>>>>> any for me.
>>>>>> I suspect that at some point, the "-t parse" logic must have been
>>>>>> removed/simplified away without realising the address format.
>>>>> Ah, good point. Maybe we can look and find said parse logic.
>>>>> http://forum.lissyara.su/viewtopic.php?f=20&t=8366#p68304
>>>>> I don't read Russian, but I did see this debug output:
>>>>>
>>>>> 2540 Filter: end of processing
>>>>> 2540 system filter returned 1
>>>>> 2540 system filter added mail-copy-mailbox@???
>>>>> 2540 system filter added mail-copy-mailbox@???
>>>>> 2540 Delivery address list:
>>>>> 2540 mail-copy-mailbox@???
>>>>> 2540 mail-copy-mailbox@???
>>>>> 2540 opt1k@???
>>>>>
>>>>> It's without the '>' mark, so there's at least a possibility that the
>>>>> simple fix is to strip the leading '>' before it prints this output.
>>>> Note that in this case, all the recipients are of type email, so the
>>>> processing which looks at the first element in the list sees the > and
>>>> strips it off all of them. In the bug case, the first recipient address
>>>> is a path, so that doesn't happen.
>>> The path was only added as an aid to debug a problem we were already
>>> having. It still occurred when we only had email address deliveries.
>> Molly, can you test to make sure that when it's only delivering to
>> email addresses that the debug output above still has the errant '>'
>> symbols present?
>>
>> ...Todd
> Appears I was wrong. The debug output doesn't have the '>' so it looks
> like the initial issue I put the save to file in there for is something
> unrelated. I won't post debug output as it's long and not relevant.
>

Ah no wait I was right in the first place - it just doesn't trip the
filters if you make a typo in your SMTP while telnetted in to the server:

Exim version 4.76 uid=0 gid=0 pid=14447 D=fbb95efd
Berkeley DB: Berkeley DB 5.1.25: (January 28, 2011)
Support for: crypteq iconv() IPv6 PAM Perl Expand_dlfunc GnuTLS
move_frozen_messages Content_Scanning DKIM Old_Demime
Lookups (built-in): lsearch wildlsearch nwildlsearch iplsearch cdb dbm
dbmnz dnsdb dsearch ldap ldapdn ldapm mysql nis nis0 passwd pgsql sqlite
Authenticators: cram_md5 cyrus_sasl dovecot plaintext spa
Routers: accept dnslookup ipliteral iplookup manualroute queryprogram
redirect
Transports: appendfile/maildir/mailstore/mbx autoreply lmtp pipe smtp
Fixed never_users: 0
Size of off_t: 8
Compiler: GCC [4.6.3]
Library version: GnuTLS: Compile: 2.12.14
                         Runtime: 2.12.14
Library version: Cyrus SASL: Compile: 2.1.25
                             Runtime: 2.1.25 [Cyrus SASL]
Library version: PCRE: Compile: 8.12
                       Runtime: 8.12 2011-01-15
Total 18 lookups
Library version: MySQL: Compile: 5.5.28 [(Ubuntu)]
                        Runtime: 5.5.24
Library version: SQLite: Compile: 3.7.9
                         Runtime: 3.7.9
WHITELIST_D_MACROS: "OUTGOING"
TRUSTED_CONFIG_LIST: "/etc/exim4/trusted_configs"
changed uid/gid: forcing real = effective
  uid=0 gid=0 pid=14447
  auxiliary group list: <none>
seeking password data for user "root": cache not available
getpwnam() succeeded uid=0 gid=0
seeking password data for user "Debian-exim": cache not available
getpwnam() succeeded uid=104 gid=112
configuration file is /etc/exim4/exim4.conf
log selectors = ffffffff 7fffffff
LOG: MAIN
  cwd=/root 5 args: /usr/sbin/exim4 -bd -oX 2525 -d+filter
trusted user
admin user
seeking password data for user "Debian-exim": using cached result
getpwnam() succeeded uid=104 gid=112
seeking password data for user "list": cache not available
getpwnam() succeeded uid=38 gid=38
originator: uid=0 gid=0 login=root name=root
14447 daemon_smtp_port overridden by -oX:
14447   <: 2525
14447 listening on all interfaces (IPv6) port 2525
14447 listening on all interfaces (IPv4) port 2525
14447 changed uid/gid: running as a daemon
14447   uid=104 gid=112 pid=14447
14447   auxiliary group list: 112
14447 LOG: MAIN
14447   exim 4.76 daemon started: pid=14447, no queue runs, listening
for SMTP on port 2525 (IPv6 and IPv4)
14447 set_process_info: 14447 daemon: no queue runs, listening for SMTP
on port 2525 (IPv6 and IPv4)
14447 daemon running with uid=104 gid=112 euid=104 egid=112
14447 Listening...
14447 Connection request from 10.82.128.63 port 54058
14447 LOG: smtp_connection MAIN
14447   SMTP connection from [10.82.128.63]:54058 I=[10.82.1.26]:2525
(TCP/IP connection count = 1)
14447 search_tidyup called
14447 1 SMTP accept process running
14447 Listening...
14451 sender_fullhost = [10.82.128.63]:54058
14451 sender_rcvhost = [10.82.128.63] (port=54058)
14451 Process 14451 is handling incoming connection from
[10.82.128.63]:54058
14451 host in host_lookup? yes (matched "*")
14451 looking up host name for 10.82.128.63
14451 DNS lookup of 63.128.82.10.in-addr.arpa (PTR) succeeded
14451 IP address lookup yielded moli7.redembedded.com
14451 gethostbyname2(af=inet6) returned 3 (NO_RECOVERY)
14451 gethostbyname2 looked up these IP addresses:
14451   name=moli7.redembedded.com address=10.82.128.63
14451 checking addresses for moli7.redembedded.com
14451   10.82.128.63 OK
14451 sender_fullhost = moli7.redembedded.com [10.82.128.63]:54058
14451 sender_rcvhost = moli7.redembedded.com ([10.82.128.63]:54058)
14451 set_process_info: 14451 handling incoming connection from
moli7.redembedded.com [10.82.128.63]:54058 I=[10.82.1.26]:2525
14451 host in host_reject_connection? no (option unset)
14451 host in sender_unqualified_hosts? no (option unset)
14451 host in recipient_unqualified_hosts? no (option unset)
14451 host in helo_verify_hosts? no (option unset)
14451 host in helo_try_verify_hosts? no (option unset)
14451 host in helo_accept_junk_hosts? no (option unset)
14451 SMTP>> 220 mail1.redembedded.com ESMTP Exim 4.76 Tue, 30 Oct 2012
15:02:01 +0000
14451 Process 14451 is ready for new message
14451 smtp_setup_msg entered
14451 SMTP<< Mail from: molly.fletcher@???
14451 SMTP>> 250 OK
14451 SMTP<< rcpt to: fakeuser@???
14451 using ACL "acl_check_rcpt"
14451 processing "warn"
14451 check control = dkim_disable_verify
14451 warn: condition test succeeded
14451 processing "accept"
14451 check hosts = :
14451 host in ":"? no (end of list)
14451 accept: condition test failed
14451 processing "deny"
14451 check domains = +local_domains
14451 bigcorp.com in "redembedded.co.uk : redembedded.com :
mail.redembedded.com : mail.redembedded.co.uk : jess.redembedded.com :
jess.redembedded.co.uk : localhost"? no (end of list)
14451 bigcorp.com in "+local_domains"? no (end of list)
14451 deny: condition test failed
14451 processing "deny"
14451 check domains = !+local_domains
14451 cached no match for +local_domains
14451 cached lookup data = NULL
14451 bigcorp.com in "!+local_domains"? yes (end of list)
14451 check local_parts = ^[./|] : ^.*[@%!] : ^.*/\\.\\./
14451 fakeuser in "^[./|] : ^.*[@%!] : ^.*/\.\./"? no (end of list)
14451 deny: condition test failed
14451 processing "accept"
14451 check local_parts = postmaster
14451 fakeuser in "postmaster"? no (end of list)
14451 accept: condition test failed
14451 processing "accept"
14451 check local_parts = cv
14451 fakeuser in "cv"? no (end of list)
14451 accept: condition test failed
14451 processing "warn"
14451 check dnslists = blackholes.mail-abuse.org : dialup.mail-abuse.org
14451 DNS list check: blackholes.mail-abuse.org
14451 new DNS lookup for 63.128.82.10.blackholes.mail-abuse.org
14451 DNS lookup of 63.128.82.10.blackholes.mail-abuse.org (A) gave
HOST_NOT_FOUND
14451 returning DNS_NOMATCH
14451 DNS lookup for 63.128.82.10.blackholes.mail-abuse.org failed
14451 => that means 10.82.128.63 is not listed at blackholes.mail-abuse.org
14451 DNS list check: dialup.mail-abuse.org
14451 new DNS lookup for 63.128.82.10.dialup.mail-abuse.org
14451 DNS lookup of 63.128.82.10.dialup.mail-abuse.org (A) gave
HOST_NOT_FOUND
14451 returning DNS_NOMATCH
14451 DNS lookup for 63.128.82.10.dialup.mail-abuse.org failed
14451 => that means 10.82.128.63 is not listed at dialup.mail-abuse.org
14451 warn: condition test failed
14451 processing "accept"
14451 check hosts = +relay_from_hosts
14451 host in "127.0.0.1 : 10.82.0.0/16 : 10.98.237.0/24 : 82.219.14.82
: 82.219.14.194 : 82.219.14.205 : 82.219.14.206 : 82.219.14.212 :
81.138.245.218"? yes (matched "10.82.0.0/16")
14451 host in "+relay_from_hosts"? yes (matched "+relay_from_hosts")
14451 check control = submission
14451 accept: condition test succeeded
14451 SMTP>> 250 Accepted
14451 SMTP<< data
14451 SMTP>> 354 Enter message, ending with "." on a line by itself
14451 search_tidyup called
14451 host in ignore_fromline_hosts? no (option unset)
14451 >>Headers received:
14451 From: molly.fletcher@???
14451 To: fakeuser@???
14451 Subject: Filter test please ignore
14451
14451 address match: subject=fakeuser@???
pattern=root@???
14451 fakeuser@??? in "root@???"? no (end of list)
14451 address match: subject=fakeuser@???
pattern=root@???
14451 fakeuser@??? in "root@???"? no (end of list)
14451 address match: subject=fakeuser@???
pattern=*@*.redembedded.co.uk
14451 bigcorp.com in "*.redembedded.co.uk"? no (end of list)
14451 fakeuser@??? in "*@*.redembedded.co.uk"? no (end of list)
14451 address match: subject=molly.fletcher@???
pattern=root@???
14451 molly.fletcher@??? in "root@???"? no (end
of list)
14451 address match: subject=molly.fletcher@???
pattern=root@???
14451 molly.fletcher@??? in "root@???"? no
(end of list)
14451 address match: subject=molly.fletcher@???
pattern=*@*.redembedded.co.uk
14451 redembedded.com in "*.redembedded.co.uk"? no (end of list)
14451 molly.fletcher@??? in "*@*.redembedded.co.uk"? no (end
of list)
14451 rewritten sender = molly.fletcher@???
14451 rewrite_one_header: type=F:
14451   From: molly.fletcher@???
14451 address match: subject=molly.fletcher@???
pattern=root@???
14451 molly.fletcher@??? in "root@???"? no (end
of list)
14451 address match: subject=molly.fletcher@???
pattern=root@???
14451 molly.fletcher@??? in "root@???"? no
(end of list)
14451 address match: subject=molly.fletcher@???
pattern=*@*.redembedded.co.uk
14451 redembedded.com in "*.redembedded.co.uk"? no (end of list)
14451 molly.fletcher@??? in "*@*.redembedded.co.uk"? no (end
of list)
14451 rewrite_one_header: type=T:
14451   To: fakeuser@???
14451 address match: subject=fakeuser@???
pattern=root@???
14451 fakeuser@??? in "root@???"? no (end of list)
14451 address match: subject=fakeuser@???
pattern=root@???
14451 fakeuser@??? in "root@???"? no (end of list)
14451 address match: subject=fakeuser@???
pattern=*@*.redembedded.co.uk
14451 bigcorp.com in "*.redembedded.co.uk"? no (end of list)
14451 fakeuser@??? in "*@*.redembedded.co.uk"? no (end of list)
14451 search_tidyup called
14451 >>Headers after rewriting and local additions:
14451 F From: molly.fletcher@???
14451 T To: fakeuser@???
14451   Subject: Filter test please ignore
14451 I Message-Id: <E1TTDLS-0003l5-UZ@???>
14451   Date: Tue, 30 Oct 2012 15:03:54 +0000
14451
14451 Data file written for message 1TTDLS-0003l5-UZ
14451 >>Generated Received: header line
14451 P Received: from moli7.redembedded.com ([10.82.128.63]:54058)
14451     by mail1.redembedded.com with smtp (Exim 4.76)
14451     (envelope-from <molly.fletcher@???>)
14451     id 1TTDLS-0003l5-UZ
14451     for fakeuser@???; Tue, 30 Oct 2012 15:04:09 +0000
14451 using ACL "acl_check_data"
14451 processing "warn"
14451 check malware = *
14451 Malware scan: issuing clamd local-path scan
[/var/run/clamav/clamd.ctl]
14451 waiting for data on socket
14451 Malware response:
/var/spool/exim4/scan/1TTDLS-0003l5-UZ/1TTDLS-0003l5-UZ.eml: OK
14451 Malware not found
14451 warn: condition test failed
14451 processing "warn"
14451 check spam = nobody
14451 trying server 127.0.0.1, port 783
14451 waiting for data on socket
14451 waiting for data on socket
14451 waiting for data on socket
14451 warn: condition test failed
14451 processing "accept"
14451 accept: condition test succeeded
14451 unspool_mbox(): unlinking
'/var/spool/exim4/scan/1TTDLS-0003l5-UZ/1TTDLS-0003l5-UZ.eml'
14451 calling local_scan(); timeout=300
14451 local_scan() returned 0 NULL
14451 Writing spool header file
14451 Size of headers = 427
14451 LOG: MAIN
14451   <= molly.fletcher@??? H=moli7.redembedded.com
[10.82.128.63]:54058 I=[10.82.1.26]:2525 P=smtp S=449 T="Filter test
please ignore"
14451 SMTP>> 250 OK id=1TTDLS-0003l5-UZ
14451 search_tidyup called
14451 Sender: molly.fletcher@???
14451 Recipients:
14451   fakeuser@???
14451 forked delivery process 14483
14451 Process 14451 is ready for new message
14451 smtp_setup_msg entered
14483 exec /usr/sbin/exim4 -d=0xfbbd5efd -Mc 1TTDLS-0003l5-UZ
14483 Exim version 4.76 uid=104 gid=112 pid=14483 D=fbbd5efd
Berkeley DB: Berkeley DB 5.1.25: (January 28, 2011)
Support for: crypteq iconv() IPv6 PAM Perl Expand_dlfunc GnuTLS
move_frozen_messages Content_Scanning DKIM Old_Demime
Lookups (built-in): lsearch wildlsearch nwildlsearch iplsearch cdb dbm
dbmnz dnsdb dsearch ldap ldapdn ldapm mysql nis nis0 passwd pgsql sqlite
Authenticators: cram_md5 cyrus_sasl dovecot plaintext spa
Routers: accept dnslookup ipliteral iplookup manualroute queryprogram
redirect
Transports: appendfile/maildir/mailstore/mbx autoreply lmtp pipe smtp
Fixed never_users: 0
Size of off_t: 8
Compiler: GCC [4.6.3]
Library version: GnuTLS: Compile: 2.12.14
                         Runtime: 2.12.14
Library version: Cyrus SASL: Compile: 2.1.25
                             Runtime: 2.1.25 [Cyrus SASL]
Library version: PCRE: Compile: 8.12
                       Runtime: 8.12 2011-01-15
14483 Total 18 lookups
Library version: MySQL: Compile: 5.5.28 [(Ubuntu)]
                        Runtime: 5.5.24
Library version: SQLite: Compile: 3.7.9
                         Runtime: 3.7.9
WHITELIST_D_MACROS: "OUTGOING"
TRUSTED_CONFIG_LIST: "/etc/exim4/trusted_configs"
14483 changed uid/gid: forcing real = effective
14483   uid=0 gid=112 pid=14483
14483   auxiliary group list: <none>
14483 seeking password data for user "root": cache not available
14483 getpwnam() succeeded uid=0 gid=0
14483 seeking password data for user "Debian-exim": cache not available
14483 getpwnam() succeeded uid=104 gid=112
14483 configuration file is /etc/exim4/exim4.conf
14483 log selectors = ffffffff 7fffffff
14483 LOG: MAIN
14483   cwd=/var/spool/exim4 4 args: /usr/sbin/exim4 -d=0xfbbd5efd -Mc
1TTDLS-0003l5-UZ
14483 trusted user
14483 admin user
14483 skipping ACL configuration - not needed
14483 seeking password data for user "Debian-exim": using cached result
14483 getpwnam() succeeded uid=104 gid=112
14483 seeking password data for user "list": cache not available
14483 getpwnam() succeeded uid=38 gid=38
14483 set_process_info: 14483 delivering specified messages
14483 set_process_info: 14483 delivering 1TTDLS-0003l5-UZ
14483 reading spool file 1TTDLS-0003l5-UZ-H
14483 user=Debian-exim uid=104 gid=112 sender=molly.fletcher@???
14483 sender_fullhost = moli7.redembedded.com [10.82.128.63]:54058
14483 sender_rcvhost = moli7.redembedded.com ([10.82.128.63]:54058)
14483 sender_local=0 ident=unset
14483 Non-recipients:
14483 Empty Tree
14483 ---- End of tree ----
14483 recipients_count=1
14483 body_linecount=1 message_linecount=10
14483 running system filter
14483 rda_interpret (file): /etc/exim4/exim.filter
14483 expanded: /etc/exim4/exim.filter
14483 search_tidyup called
14484 changed uid/gid: system filter
14484   uid=104 gid=112 pid=14484
14484   auxiliary group list: <none>
14484 3145 bytes read from /etc/exim4/exim.filter
14484 data is an Exim filter program
14484 Filter: start of processing
14484 Condition is false: $header_from: contains spammer1.co.uk
14484 Condition is false: $header_to: contains root@???
14484 Condition is false: $header_from: contains spammer2.com
14484 Condition is false: $header_from: contains spammer3.com
14484 Condition is false: $header_from: contains spammer4.com
14484 Condition is false: $header_from: contains spammer5.com
14484 Condition is false: $header_from: contains spammer6.com
14484 Condition is false: $header_from: contains spammer7.com
14484 Condition is false: $header_subject: contains [mailinglist]
14484 Condition is false: $header_from: contains spammer8.com
14484 Condition is false: $header_to: contains domain.com
14484 Condition is false: $header_subject: begins Read:
14484 Condition is false: $header_from: contains spammer@???
14484 Condition is false: $h_from: contains spammer2@???
14484 Sub-condition is true: $h_to: contains bigcorp.com
14484 Sub-condition is true: $sender_address: contains @redembedded.com
14484 Condition is true: $h_to: contains bigcorp.com and
$sender_address: contains @redembedded.com
14484 Match expanded arguments:
14484   Subject = http://192.168.9.10/ :
14484   Pattern =
(http|https|ftp)://([01]?\d\d?|2[0-4]\d|25[0-5])\.([01]?\d\d?|2[0-4]\d|25[0-5])\.([01]?\d\d?|2[0-4]\d|25[0-5])\.([01]?\d\d?|2[0-4]\d|25[0-5])
14484   Condition is true: $message_body: matches
(http|https|ftp)://([01]?\\d\\d?|2[0-4]\\d|25[0-5])\\.([01]?\\d\\d?|2[0-4]\\d|25[0-5])\\.([01]?\\d\\d?|2[0-4]\\d|25[0-5])\\.([01]?\\d\\d?|2[0-4]\\d|25[0-5])
14484 Filter: mail to: molly.fletcher@???
14484         subject: WARNING-Numeric URL
14484            text: WARNING: You sent an email to a bigcorp address
which contained a numeric (IP address) URL - this WILL BE REJECTED by
BigCORPs message filtering
14484 Filtering did not set up a significant delivery.
14484 Normal delivery will occur.
14484 Filter: end of processing
14484 search_tidyup called
14483 rda_interpret: subprocess yield=1 error=NULL
14483 system filter returned 1
14483 system filter added >molly.fletcher@???
14483 Delivery address list:
14483   >molly.fletcher@???
14483   fakeuser@???
14483 locking /var/spool/exim4/db/retry.lockfile
14483 locked /var/spool/exim4/db/retry.lockfile
14483 EXIM_DBOPEN(/var/spool/exim4/db/retry)
14483 returned from EXIM_DBOPEN
14483 opened hints database /var/spool/exim4/db/retry: flags=O_RDONLY
14483 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
14483 Considering: >molly.fletcher@???
14483 unique = >molly.fletcher@???:system-filter
14483 queued for local_delivery transport
14483 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
14483 Considering: fakeuser@???
14483 unique = fakeuser@???
14483 dbfn_read: key=R:bigcorp.com
14483 dbfn_read: key=R:fakeuser@???
14483 dbfn_read: key=R:fakeuser@???:<molly.fletcher@???>
14483 no domain retry record
14483 no address retry record
14483 fakeuser@???: queued for routing
14483 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
14483 routing fakeuser@???
14483 --------> check_malware router <--------
14483 local_part=fakeuser domain=bigcorp.com
14483 checking "condition"
14483 check_malware router skipped: condition failure
14483 --------> mailman_workaround router <--------
14483 local_part=fakeuser domain=bigcorp.com
14483 checking domains
14483 bigcorp.com in "redembedded.co.uk : redembedded.com :
mail.redembedded.com : mail.redembedded.co.uk : jess.redembedded.com :
jess.redembedded.co.uk : localhost"? no (end of list)
14483 bigcorp.com in "+local_domains"? no (end of list)
14483 mailman_workaround router skipped: domains mismatch
14483 --------> mailman_router router <--------
14483 local_part=fakeuser domain=bigcorp.com
14483 checking domains
14483 cached no match for +local_domains
14483 cached lookup data = NULL
14483 bigcorp.com in "+local_domains"? no (end of list)
14483 mailman_router router skipped: domains mismatch
14483 --------> redmine_router router <--------
14483 local_part=fakeuser domain=bigcorp.com
14483 checking domains
14483 bigcorp.com in "redmine.redembedded.com"? no (end of list)
14483 redmine_router router skipped: domains mismatch
14483 --------> rednet_router router <--------
14483 local_part=fakeuser domain=bigcorp.com
14483 checking domains
14483 bigcorp.com in "rednet.redembedded.com"? no (end of list)
14483 rednet_router router skipped: domains mismatch
14483 --------> dnslookup router <--------
14483 local_part=fakeuser domain=bigcorp.com
14483 checking domains
14483 cached no match for +local_domains
14483 cached lookup data = NULL
14483 bigcorp.com in "! +local_domains"? yes (end of list)
14483 calling dnslookup router
14483 dnslookup router called for fakeuser@???
14483   domain = bigcorp.com
14483 DNS lookup of bigcorp.com (MX) succeeded
14483 DNS lookup of mail.messaging.microsoft.com (AAAA) gave NO_DATA
14483 returning DNS_NODATA
14483 DNS lookup of mail.messaging.microsoft.com (A) succeeded
14483 213.199.180.150 in "0.0.0.0 : 127.0.0.0/8"? no (end of list)
14483 216.32.180.22 in "0.0.0.0 : 127.0.0.0/8"? no (end of list)
14483 fully qualified name = bigcorp.com
14483 host_find_bydns yield = HOST_FOUND (2); returned hosts:
14483   mail.messaging.microsoft.com 216.32.180.22 MX=10
14483   mail.messaging.microsoft.com 213.199.180.150 MX=10
14483 set transport remote_smtp
14483 queued for remote_smtp transport: local_part = fakeuser
14483 domain = bigcorp.com
14483   errors_to=NULL
14483   domain_data=NULL localpart_data=NULL
14483 routed by dnslookup router
14483   envelope to: fakeuser@???
14483   transport: remote_smtp
14483   host mail.messaging.microsoft.com [216.32.180.22] MX=10
14483   host mail.messaging.microsoft.com [213.199.180.150] MX=10
14483 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
14483 After routing:
14483   Local deliveries:
14483     >molly.fletcher@???
14483   Remote deliveries:
14483     fakeuser@???
14483   Failed addresses:
14483   Deferred addresses:
14483 search_tidyup called
14483 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
14483 --------> >molly.fletcher@??? <--------
14483 locking /var/spool/exim4/db/retry.lockfile
14483 locked /var/spool/exim4/db/retry.lockfile
14483 EXIM_DBOPEN(/var/spool/exim4/db/retry)
14483 returned from EXIM_DBOPEN
14483 opened hints database /var/spool/exim4/db/retry: flags=O_RDONLY
14483 dbfn_read: key=T:>molly.fletcher@???:system-filter
14483 no retry record exists
14483 search_tidyup called
14485 changed uid/gid: local delivery to >molly.fletcher@???
<>molly.fletcher@???> transport=local_delivery
14485   uid=104 gid=112 pid=14485
14485   auxiliary group list: <none>
14485   home=NULL current=/
14485 set_process_info: 14485 delivering 1TTDLS-0003l5-UZ to

>molly.fletcher@??? using local_delivery

14485 local_delivery transport entered
14485   >molly.fletcher@???
14485 using the transport's hosts: exmail.redembedded.com
14485 getting address for exmail.redembedded.com
14485 DNS lookup of exmail.redembedded.com (AAAA) gave NO_DATA
14485 returning DNS_NODATA
14485 DNS lookup of exmail.redembedded.com (A) succeeded
14485 fully qualified name = exmail.redembedded.com
14485 exmail.redembedded.com 10.82.128.38 mx=-1 sort=-363
14485 redembedded.com in queue_smtp_domains? no (option unset)
14485 checking status of exmail.redembedded.com
14485 locking /var/spool/exim4/db/retry.lockfile
14485 locked /var/spool/exim4/db/retry.lockfile
14485 EXIM_DBOPEN(/var/spool/exim4/db/retry)
14485 returned from EXIM_DBOPEN
14485 opened hints database /var/spool/exim4/db/retry: flags=O_RDONLY
14485 dbfn_read: key=T:exmail.redembedded.com:10.82.128.38
14485 dbfn_read: key=T:exmail.redembedded.com:10.82.128.38:1TTDLS-0003l5-UZ
14485 no host retry record
14485 no message retry record
14485 exmail.redembedded.com [10.82.128.38] status = usable
14485 10.82.128.38 in serialize_hosts? no (option unset)
14485 delivering 1TTDLS-0003l5-UZ to exmail.redembedded.com
[10.82.128.38] (>molly.fletcher@???)
14485 set_process_info: 14485 delivering 1TTDLS-0003l5-UZ to
exmail.redembedded.com [10.82.128.38] (>molly.fletcher@???)
14485 Connecting to exmail.redembedded.com [10.82.128.38]:25 ... connected
14485 waiting for data on socket
14485 read response data: size=101
14485   SMTP<< 220 winky.red.redembedded.com Microsoft ESMTP MAIL
Service ready at Tue, 30 Oct 2012 15:04:09 +0000
14485 10.82.128.38 in hosts_avoid_esmtp? no (option unset)
14485   SMTP>> EHLO mail1.redembedded.com
14485 waiting for data on socket
14485 read response data: size=264
14485   SMTP<< 250-winky.red.redembedded.com Hello [10.82.1.26]
14485          250-SIZE
14485          250-PIPELINING
14485          250-DSN
14485          250-ENHANCEDSTATUSCODES
14485          250-STARTTLS
14485          250-X-ANONYMOUSTLS
14485          250-AUTH NTLM
14485          250-X-EXPS GSSAPI NTLM
14485          250-8BITMIME
14485          250-BINARYMIME
14485          250-CHUNKING
14485          250-XEXCH50
14485          250-XRDST
14485          250 XSHADOW
14485 10.82.128.38 in hosts_require_tls? no (option unset)
14485 10.82.128.38 in hosts_avoid_pipelining? no (option unset)
14485 not using PIPELINING
14485 10.82.128.38 in hosts_require_auth? no (option unset)
14485   SMTP>> MAIL FROM:<molly.fletcher@???>
14485 waiting for data on socket
14485 read response data: size=21
14485   SMTP<< 250 2.1.0 Sender OK
14485   SMTP>> RCPT TO:<>molly.fletcher@???>
14485 waiting for data on socket
14485 read response data: size=27
14485   SMTP<< 501 5.1.3 Invalid address
14485 ok=1 send_quit=1 send_rset=1 continue_more=0 yield=0 first_address
is NULL
14485 transport_check_waiting entered
14485   sequence=1 local_max=500 global_max=-1
14485 locking /var/spool/exim4/db/wait-local_delivery.lockfile
14485 locked /var/spool/exim4/db/wait-local_delivery.lockfile
14485 EXIM_DBOPEN(/var/spool/exim4/db/wait-local_delivery)
14485 returned from EXIM_DBOPEN
14485 opened hints database /var/spool/exim4/db/wait-local_delivery:
flags=O_RDWR
14485 dbfn_read: key=exmail.redembedded.com
14485 no messages waiting for exmail.redembedded.com
14485   SMTP>> QUIT
14485 set_process_info: 14485 delivering 1TTDLS-0003l5-UZ: just tried
exmail.redembedded.com [10.82.128.38] for

>molly.fletcher@???: result OK

14485 Leaving local_delivery transport
14485 search_tidyup called
14483 local_delivery transport returned FAIL for
>molly.fletcher@???

14483 post-process >molly.fletcher@??? (2)
14483 LOG: MAIN
14483 ** >molly.fletcher@??? <system-filter>
F=<molly.fletcher@???> P=<molly.fletcher@???>
T=local_delivery: SMTP error from remote mail server after RCPT
TO:<>molly.fletcher@???>: host exmail.redembedded.com
[10.82.128.38]: 501 5.1.3 Invalid address
14483 >>>>>>>>>>>>>>>> Remote deliveries >>>>>>>>>>>>>>>>

Snipped the remote deliveries as not really relevant.

-- 
Molly Fletcher, IT System Administrator    <molly.fletcher@???>
Red Embedded Design            www.redembedded.com
Tel: +44 (0)1274 287724


This E-mail and any attachments hereto are strictly confidential and intended solely for the addressee. If you are not the intended addressee please notify the sender by return and delete the message.

You must not disclose, forward or copy this E-mail or attachments to any third party without the prior consent of the sender.

Red Embedded Design Registered in England, Company Number: 06688253
Registered Office: The Waterfront, Salts Mill Road, Saltaire, West Yorkshire, BD17 7EZ