Re: [exim] Problem with Maildir delivery

Top Page
Delete this message
Reply to this message
Author: Peter Whysall
Date:  
To: exim-users
Subject: Re: [exim] Problem with Maildir delivery
Peter Bowyer wrote:
> Are the user(s) in question being caught by your procmail or maildrop
> routers?
>
> Please post the output of a debug delivery.


I hope this is what you want; it's rather long.




Starting MTA: Exim version 4.34 uid=0 gid=0 pid=16988 D=fbb95cfd
Berkeley DB: Sleepycat Software: Berkeley DB 3.2.9: (June 20, 2004)
Support for: iconv() IPv6 PAM Perl GnuTLS
Lookups: lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmnz dnsdb 
dsearch ldap ldapdn ldapm mysql nis nis0 passwd pgsql
Authenticators: cram_md5 plaintext spa
Routers: accept dnslookup ipliteral iplookup manualroute queryprogram 
redirect
Transports: appendfile/maildir/mailstore/mbx autoreply lmtp pipe smtp
Fixed never_users: 0
changed uid/gid: forcing real = effective
   uid=0 gid=0 pid=16988
   auxiliary group list: <none>
configuration file is /var/lib/exim4/config.autogenerated
log selectors = 00000ffc 00010400
trusted user
admin user
user name "root" extracted from gecos field "root"
originator: uid=0 gid=0 login=root name=root
16988 listening on all interfaces (IPv6) port 25
16988 listening on all interfaces (IPv4) port 25
16988 pid written to /var/run/exim4/exim.pid
16988 changed uid/gid: running as a daemon
16988   uid=112 gid=112 pid=16988
16988   auxiliary group list: 112
16988 LOG: MAIN
16988   exim 4.34 daemon started: pid=16988, -q30m, listening for SMTP 
on port 25 (IPv6 and IPv4)
16988 set_process_info: 16988 daemon: -q30m, listening for SMTP on port 
25 (IPv6 and IPv4)
16988 daemon running with uid=112 gid=112 euid=112 egid=112
16988 SIGALRM received
16989 Starting queue-runner: pid 16989
16989 exec /usr/sbin/exim4 -q
16988 1 queue-runner process running
16988 Listening...
16988 Connection request from 64.233.170.204 port 16899
16988 search_tidyup called
16991 host in rfc1413_hosts? yes (matched "*")
16991 doing ident callback
16988 1 SMTP accept process running
16988 child 16989 ended: status=0x0
16988 0 queue-runner processes now running
16988 Listening...
16991 ident connection to 64.233.170.204 failed: Connection timed out
16991 sender_fullhost = [64.233.170.204]
16991 sender_rcvhost = [64.233.170.204]
16991 Process 16991 is handling incoming connection from [64.233.170.204]
16991 host in host_lookup? yes (matched "*")
16991 looking up host name for 64.233.170.204
16991 DNS lookup of 204.170.233.64.in-addr.arpa (PTR) succeeded
16991 IP address lookup yielded rproxy.gmail.com
16991 gethostbyname2(af=inet6) returned 1 (HOST_NOT_FOUND)
16991 gethostbyname2 looked up these IP addresses:
16991   name=rproxy.gmail.com address=64.233.170.202
16991   name=rproxy.gmail.com address=64.233.170.203
16991   name=rproxy.gmail.com address=64.233.170.204
16991   name=rproxy.gmail.com address=64.233.170.205
16991   name=rproxy.gmail.com address=64.233.170.206
16991   name=rproxy.gmail.com address=64.233.170.207
16991   name=rproxy.gmail.com address=64.233.170.192
16991   name=rproxy.gmail.com address=64.233.170.193
16991 checking addresses for rproxy.gmail.com
16991   64.233.170.202
16991   64.233.170.203
16991   64.233.170.204 OK
16991 sender_fullhost = rproxy.gmail.com [64.233.170.204]
16991 sender_rcvhost = rproxy.gmail.com ([64.233.170.204])
16991 set_process_info: 16991 handling incoming connection from 
rproxy.gmail.com [64.233.170.204]
16991 host in host_reject_connection? no (option unset)
16991 gethostbyname2(af=inet6) returned 1 (HOST_NOT_FOUND)
16991 gethostbyname2 looked up these IP addresses:
16991   name=ariel address=10.200.1.50
16991 host in sender_unqualified_hosts? no (end of list)
16991 gethostbyname2(af=inet6) returned 1 (HOST_NOT_FOUND)
16991 gethostbyname2 looked up these IP addresses:
16991   name=ariel address=10.200.1.50
16991 host in recipient_unqualified_hosts? no (end of list)
16991 host in helo_verify_hosts? no (option unset)
16991 host in helo_try_verify_hosts? no (option unset)
16991 host in helo_accept_junk_hosts? no (option unset)
16991 SMTP>> 220 ariel.tranquillity.lan ESMTP Exim 4.34 Wed, 29 Dec 2004 
22:36:51 +0000
16991 Process 16991 is ready for new message
16991 smtp_setup_msg entered
16991 SMTP<< EHLO rproxy.gmail.com
16991 sender_fullhost = rproxy.gmail.com [64.233.170.204]
16991 sender_rcvhost = rproxy.gmail.com ([64.233.170.204])
16991 set_process_info: 16991 handling incoming connection from 
rproxy.gmail.com [64.233.170.204]
16991 host in pipelining_advertise_hosts? yes (matched "*")
16991 host in auth_advertise_hosts? yes (matched "*")
16991 host in tls_advertise_hosts? no (option unset)
16991 SMTP>> 250-ariel.tranquillity.lan Hello rproxy.gmail.com 
[64.233.170.204]
16991 250-SIZE 52428800
16991 250-PIPELINING
16991 250 HELP
16991 SMTP<< MAIL FROM:<peter.whysall@???>
16991 SMTP>> 250 OK
16991 SMTP<< RCPT TO:<peter@???>
16991 using ACL "acl_check_rcpt"
16991 processing "accept"
16991 check hosts = :
16991 host in ":"? no (end of list)
16991 accept: condition test failed
16991 processing "deny"
16991 check domains = +local_domains
16991 guildenstern.dyndns.org in 
"@:guildenstern.dyndns.org:localhost:tranquillity.lan:ariel.tranquillity.lan:cordelia.tranquillity.lan:guildenstern.dyndns.org"? 
yes (matched "guildenstern.dyndns.org")
16991 guildenstern.dyndns.org in "+local_domains"? yes (matched 
"+local_domains")
16991 check local_parts = ^[.] : ^.*[@%!/|]
16991 peter in "^[.] : ^.*[@%!/|]"? no (end of list)
16991 deny: condition test failed
16991 processing "deny"
16991 check domains = !+local_domains
16991 cached yes match for +local_domains
16991 cached lookup data = NULL
16991 guildenstern.dyndns.org in "!+local_domains"? no (matched 
"!+local_domains" - cached)
16991 deny: condition test failed
16991 processing "accept"
16991 check local_parts = postmaster
16991 peter in "postmaster"? no (end of list)
16991 accept: condition test failed
16991 processing "deny"
16991 check !acl = acl_whitelist_local_deny
16991 using ACL "acl_whitelist_local_deny"
16991 processing "accept"
16991 check hosts = ${if 
exists{/etc/exim4/local_host_whitelist}{/etc/exim4/local_host_whitelist}{}}
16991 host in ""? no (end of list)
16991 accept: condition test failed
16991 processing "accept"
16991 check senders = ${if 
exists{/etc/exim4/local_sender_whitelist}{/etc/exim4/local_sender_whitelist}{}}
16991 peter.whysall@??? in ""? no (end of list)
16991 accept: condition test failed
16991 end of ACL "acl_whitelist_local_deny": implicit DENY
16991 check senders = ${if 
exists{/etc/exim4/local_sender_blacklist}{/etc/exim4/local_sender_blacklist}{}}
16991 peter.whysall@??? in ""? no (end of list)
16991 deny: condition test failed
16991 processing "deny"
16991 check !acl = acl_whitelist_local_deny
16991 using ACL "acl_whitelist_local_deny"
16991 processing "accept"
16991 check hosts = ${if 
exists{/etc/exim4/local_host_whitelist}{/etc/exim4/local_host_whitelist}{}}
16991 host in ""? no (end of list)
16991 accept: condition test failed
16991 processing "accept"
16991 check senders = ${if 
exists{/etc/exim4/local_sender_whitelist}{/etc/exim4/local_sender_whitelist}{}}
16991 peter.whysall@??? in ""? no (end of list)
16991 accept: condition test failed
16991 end of ACL "acl_whitelist_local_deny": implicit DENY
16991 check hosts = ${if 
exists{/etc/exim4/local_host_blacklist}{/etc/exim4/local_host_blacklist}{}}
16991 host in ""? no (end of list)
16991 deny: condition test failed
16991 processing "accept"
16991 check domains = +local_domains
16991 cached yes match for +local_domains
16991 cached lookup data = NULL
16991 guildenstern.dyndns.org in "+local_domains"? yes (matched 
"+local_domains" - cached)
16991 check verify = recipient
16991 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
16991 Verifying peter@???
16991 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
16991 Considering peter@???
16991 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
16991 routing peter@???
16991 --------> hubbed_hosts router <--------
16991 local_part=peter domain=guildenstern.dyndns.org
16991 checking domains
16991 expansion of "${if 
exists{/etc/exim4/hubbed_hosts}{partial-lsearch;/etc/exim4/hubbed_hosts}fail}" 
forced failure: assume not in this list
16991 hubbed_hosts router skipped: domains mismatch
16991 --------> dnslookup_relay_to_domains router <--------
16991 local_part=peter domain=guildenstern.dyndns.org
16991 checking domains
16991 cached yes match for +local_domains
16991 cached lookup data = NULL
16991 guildenstern.dyndns.org in "! +local_domains : +relay_to_domains"? 
no (matched "! +local_domains" - cached)
16991 dnslookup_relay_to_domains router skipped: domains mismatch
16991 --------> send_to_gateway router <--------
16991 local_part=peter domain=guildenstern.dyndns.org
16991 checking domains
16991 cached yes match for +local_domains
16991 cached lookup data = NULL
16991 guildenstern.dyndns.org in "!+local_domains"? no (matched 
"!+local_domains" - cached)
16991 send_to_gateway router skipped: domains mismatch
16991 --------> real_local router <--------
16991 local_part=peter domain=guildenstern.dyndns.org
16991 real_local router skipped: prefix mismatch
16991 --------> spamcheck_router router <--------
16991 local_part=peter domain=guildenstern.dyndns.org
16991 spamcheck_router router skipped: verify 2 0 0
16991 --------> system_aliases router <--------
16991 local_part=peter domain=guildenstern.dyndns.org
16991 checking domains
16991 cached yes match for +local_domains
16991 cached lookup data = NULL
16991 guildenstern.dyndns.org in "+local_domains"? yes (matched 
"+local_domains" - cached)
16991 R: system_aliases for peter@???
16991 calling system_aliases router
16991 rda_interpret (string): ${lookup{$local_part}lsearch{/etc/aliases}}
16991 search_open: lsearch "/etc/aliases"
16991 search_find: file="/etc/aliases"
16991   key="peter" partial=-1 affix=NULL starflags=0
16991 LRU list:
16991   :/etc/aliases
16991   End
16991 internal_search_find: file="/etc/aliases"
16991   type=lsearch key="peter"
16991 file lookup required for peter
16991   in /etc/aliases
16991 lookup failed
16991 expanded:
16991 file is not a filter file
16991 parse_forward_list:
16991 system_aliases router declined for peter@???
16991 --------> mailman_router router <--------
16991 local_part=peter domain=guildenstern.dyndns.org
16991 checking domains
16991 guildenstern.dyndns.org in "guildenstern.dyndns.org"? yes (matched 
"guildenstern.dyndns.org")
16991 guildenstern.dyndns.org in "+mm_domains"? yes (matched "+mm_domains")
16991 checking require_files
16991 file check: /var/lib/mailman/lists/${lc:$local_part}/config.pck
16991 expanded file: /var/lib/mailman/lists/peter/config.pck
16991 stat() yielded -1
16991 errno = 2
16991 mailman_router router skipped: file check
16991 --------> userforward router <--------
16991 local_part=peter domain=guildenstern.dyndns.org
16991 userforward router skipped: verify 2 0 0
16991 --------> procmail router <--------
16991 local_part=peter domain=guildenstern.dyndns.org
16991 procmail router skipped: verify 2 0 0
16991 --------> maildrop router <--------
16991 local_part=peter domain=guildenstern.dyndns.org
16991 maildrop router skipped: verify 2 0 0
16991 --------> local_user router <--------
16991 local_part=peter domain=guildenstern.dyndns.org
16991 checking domains
16991 cached yes match for +local_domains
16991 cached lookup data = NULL
16991 guildenstern.dyndns.org in "+local_domains"? yes (matched 
"+local_domains" - cached)
16991 checking local_parts
16991 peter in "! root"? yes (end of list)
16991 checking for local user
16991 R: local_user for peter@???
16991 calling local_user router
16991 local_user router called for peter@???
16991   domain = guildenstern.dyndns.org
16991 set transport maildir_home
16991 queued for maildir_home transport: local_part = peter
16991 domain = guildenstern.dyndns.org
16991   errors_to=NULL
16991   domain_data=NULL localpart_data=NULL
16991 routed by local_user router
16991   envelope to: peter@???
16991   transport: maildir_home
16991 ----------- end verify ------------
16991 accept: condition test succeeded
16991 SMTP>> 250 Accepted
16991 SMTP<< DATA
16991 SMTP>> 354 Enter message, ending with "." on a line by itself
16991 search_tidyup called
16991 host in ignore_fromline_hosts? no (option unset)
16991 >>Headers received:
16991 Received: by rproxy.gmail.com with SMTP id r35so186500rna
16991         for <peter@???>; Wed, 29 Dec 2004 
14:36:19 -0800 (PST)
16991 DomainKey-Signature: a=rsa-sha1; q=dns; c=nofws;
16991         s=beta; d=gmail.com;
16991 
h=received:message-id:date:from:user-agent:x-accept-language:mime-version:to:subject:content-type:content-transfer-encoding;
16991 
b=OLDQ5Tkgm6q4JEpedf/l3+FcijJ9WpmWi1WFvU7t/xcmPZJ+CCHWSkxHaTz2uNA1KOeuAHIiWdRc5akoaqaKG0gH+bCDZrsI2nl0UNsYUbxqwCFUVqz6vnYlN3PZUl8CUM1eoqGXVd31qvMKclj71/7+PvljUZ1qRIenAK8Suqc=
16991 Received: by 10.38.72.27 with SMTP id u27mr10494rna;
16991         Wed, 29 Dec 2004 14:36:19 -0800 (PST)
16991 Return-Path: <peter.whysall@???>
16991 Received: from ?10.200.1.2? ([82.9.221.19])
16991         by smtp.gmail.com with ESMTP id 
59sm1915rnb.2004.12.29.14.36.18;
16991         Wed, 29 Dec 2004 14:36:19 -0800 (PST)
16991 Message-ID: <41D33163.9040007@???>
16991 Date: Wed, 29 Dec 2004 22:36:19 +0000
16991 From: Peter Whysall <peter.whysall@???>
16991 User-Agent: Mozilla Thunderbird 1.0 (Windows/20041206)
16991 X-Accept-Language: en-us, en
16991 MIME-Version: 1.0
16991 To:  peter@???
16991 Subject: ROUTING TEST MESSAGE
16991 Content-Type: text/plain; charset=ISO-8859-1; format=flowed
16991 Content-Transfer-Encoding: 7bit
16991
16991 address match: subject=peter.whysall@??? 
pattern=*@+local_domains
16991 gmail.com in 
"@:guildenstern.dyndns.org:localhost:tranquillity.lan:ariel.tranquillity.lan:cordelia.tranquillity.lan:guildenstern.dyndns.org"? 
no (end of list)
16991 gmail.com in "+local_domains"? no (end of list)
16991 peter.whysall@??? in "*@+local_domains"? no (end of list)
16991 address match: subject=peter.whysall@??? 
pattern=*@+local_domains
16991 gmail.com in 
"@:guildenstern.dyndns.org:localhost:tranquillity.lan:ariel.tranquillity.lan:cordelia.tranquillity.lan:guildenstern.dyndns.org"? 
no (end of list)
16991 gmail.com in "+local_domains"? no (end of list)
16991 peter.whysall@??? in "*@+local_domains"? no (end of list)
16991 address match: subject=peter.whysall@??? 
pattern=*@*.tranquillity.lan
16991 gmail.com in "*.tranquillity.lan"? no (end of list)
16991 peter.whysall@??? in "*@*.tranquillity.lan"? no (end of list)
16991 rewritten sender = peter.whysall@???
16991 rewrite_one_header: type=F:
16991   From: Peter Whysall <peter.whysall@???>
16991 address match: subject=peter.whysall@??? 
pattern=*@+local_domains
16991 gmail.com in 
"@:guildenstern.dyndns.org:localhost:tranquillity.lan:ariel.tranquillity.lan:cordelia.tranquillity.lan:guildenstern.dyndns.org"? 
no (end of list)
16991 gmail.com in "+local_domains"? no (end of list)
16991 peter.whysall@??? in "*@+local_domains"? no (end of list)
16991 address match: subject=peter.whysall@??? 
pattern=*@+local_domains
16991 gmail.com in 
"@:guildenstern.dyndns.org:localhost:tranquillity.lan:ariel.tranquillity.lan:cordelia.tranquillity.lan:guildenstern.dyndns.org"? 
no (end of list)
16991 gmail.com in "+local_domains"? no (end of list)
16991 peter.whysall@??? in "*@+local_domains"? no (end of list)
16991 address match: subject=peter.whysall@??? 
pattern=*@*.tranquillity.lan
16991 gmail.com in "*.tranquillity.lan"? no (end of list)
16991 peter.whysall@??? in "*@*.tranquillity.lan"? no (end of list)
16991 rewrite_one_header: type=T:
16991   To:  peter@???
16991 search_tidyup called
16991 >>Headers after rewriting and local additions:
16991 P Received: by rproxy.gmail.com with SMTP id r35so186500rna
16991         for <peter@???>; Wed, 29 Dec 2004 
14:36:19 -0800 (PST)
16991   DomainKey-Signature: a=rsa-sha1; q=dns; c=nofws;
16991         s=beta; d=gmail.com;
16991 
h=received:message-id:date:from:user-agent:x-accept-language:mime-version:to:subject:content-type:content-transfer-encoding;
16991 
b=OLDQ5Tkgm6q4JEpedf/l3+FcijJ9WpmWi1WFvU7t/xcmPZJ+CCHWSkxHaTz2uNA1KOeuAHIiWdRc5akoaqaKG0gH+bCDZrsI2nl0UNsYUbxqwCFUVqz6vnYlN3PZUl8CUM1eoqGXVd31qvMKclj71/7+PvljUZ1qRIenAK8Suqc=
16991 P Received: by 10.38.72.27 with SMTP id u27mr10494rna;
16991         Wed, 29 Dec 2004 14:36:19 -0800 (PST)
16991 * Return-Path: <peter.whysall@???>
16991 P Received: from ?10.200.1.2? ([82.9.221.19])
16991         by smtp.gmail.com with ESMTP id 
59sm1915rnb.2004.12.29.14.36.18;
16991         Wed, 29 Dec 2004 14:36:19 -0800 (PST)
16991 I Message-ID: <41D33163.9040007@???>
16991   Date: Wed, 29 Dec 2004 22:36:19 +0000
16991 F From: Peter Whysall <peter.whysall@???>
16991   User-Agent: Mozilla Thunderbird 1.0 (Windows/20041206)
16991   X-Accept-Language: en-us, en
16991   MIME-Version: 1.0
16991 T To:  peter@???
16991   Subject: ROUTING TEST MESSAGE
16991   Content-Type: text/plain; charset=ISO-8859-1; format=flowed
16991   Content-Transfer-Encoding: 7bit
16991
16991 Data file written for message 1CjmR1-0004Q3-JV
16991 >>Generated Received: header line
16991 P Received: from rproxy.gmail.com ([64.233.170.204])
16991   by ariel.tranquillity.lan with esmtp (Exim 4.34)
16991   id 1CjmR1-0004Q3-JV
16991   for peter@???; Wed, 29 Dec 2004 22:36:51 +0000
16991 using ACL "acl_check_data"
16991 processing "deny"
16991 check demime = *
16991 check condition = ${if >{$demime_errorlevel}{2}{1}{0}}
16991                 = 0
16991 deny: condition test failed
16991 processing "deny"
16991 check demime = ade:adp:bas:bat:chm:cmd:com:cpl:crt:exe:hlp:hta 
:inf:ins:isp:js:jse:lnk:mdb:mde:msc:msi:msp:mst:pcd:pif:reg:scr:sct:shs:shb:url:vb:vbe:vbs:wsc:ws 
f:wsh
16991 deny: condition test failed
16991 processing "deny"
16991 check !senders = :
16991 address match: subject=peter.whysall@??? pattern=
16991 gmail.com in ""? no (end of list)
16991 peter.whysall@??? in ":"? no (end of list)
16991 check condition = ${if !def:h_Message-ID: {1}}
16991                 =
16991 deny: condition test failed
16991 processing "accept"
16991 accept: condition test succeeded
16991 unspool_mbox(): unlinking 
'/var/spool/exim4/scan/1CjmR1-0004Q3-JV/1CjmR1-0004Q3-JV.eml'
16991 unspool_mbox(): unlinking 
'/var/spool/exim4/scan/1CjmR1-0004Q3-JV/1CjmR1-0004Q3-JV-00000.com'
16991 calling local_scan(); timeout=300
16991 local_scan() returned 0 NULL
16991 Writing spool header file
16991 Size of headers = 1371
16991 LOG: MAIN
16991   <= peter.whysall@??? H=rproxy.gmail.com [64.233.170.204] 
P=esmtp S=1372 id=41D33163.9040007@???
16991 SMTP>> 250 OK id=1CjmR1-0004Q3-JV
16991 search_tidyup called
16991 Sender: peter.whysall@???
16991 Recipients:
16991   peter@???
16993 exec /usr/sbin/exim4 -d=0xfbbd5cfd -Mc 1CjmR1-0004Q3-JV
16991 forked delivery process 16993
16991 Process 16991 is ready for new message
16991 smtp_setup_msg entered
16993 Exim version 4.34 uid=112 gid=112 pid=16993 D=fbbd5cfd
Berkeley DB: Sleepycat Software: Berkeley DB 3.2.9: (June 20, 2004)
Support for: iconv() IPv6 PAM Perl GnuTLS
Lookups: lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmnz dnsdb 
dsearch ldap ldapdn ldapm mysql nis nis0 passwd pgsql
Authenticators: cram_md5 plaintext spa
Routers: accept dnslookup ipliteral iplookup manualroute queryprogram 
redirect
Transports: appendfile/maildir/mailstore/mbx autoreply lmtp pipe smtp
Fixed never_users: 0
16993 changed uid/gid: forcing real = effective
16993   uid=0 gid=112 pid=16993
16993   auxiliary group list: <none>
16993 configuration file is /var/lib/exim4/config.autogenerated
16993 log selectors = 00000ffc 00010400
16993 trusted user
16993 admin user
16993 skipping ACL configuration - not needed
16993 set_process_info: 16993 delivering specified messages
16993 set_process_info: 16993 delivering 1CjmR1-0004Q3-JV
16993 reading spool file 1CjmR1-0004Q3-JV-H
16993 user=root uid=0 gid=0 sender=peter.whysall@???
16993 sender_fullhost = rproxy.gmail.com [64.233.170.204]
16993 sender_rcvhost = rproxy.gmail.com ([64.233.170.204])
16993 sender_local=0 ident=unset
16993 Non-recipients:
16993 Empty Tree
16993 ---- End of tree ----
16993 recipients_count=1
16993 body_linecount=0 message_linecount=25
16993 Delivery address list:
16993   peter@???
16993 locking /var/spool/exim4/db/retry.lockfile
16993 locked /var/spool/exim4/db/retry.lockfile
16993 opened hints database /var/spool/exim4/db/retry: flags=0
16993 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
16993 Considering: peter@???
16993 unique = peter@???
16993 dbfn_read: key=R:guildenstern.dyndns.org
16993 dbfn_read: key=R:peter@???
16993 no domain retry record
16993 no address retry record
16993 peter@???: queued for routing
16993 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
16993 routing peter@???
16993 --------> hubbed_hosts router <--------
16993 local_part=peter domain=guildenstern.dyndns.org
16993 checking domains
16993 expansion of "${if 
exists{/etc/exim4/hubbed_hosts}{partial-lsearch;/etc/exim4/hubbed_hosts}fail}" 
forced failure: assume not in this list
16993 hubbed_hosts router skipped: domains mismatch
16993 --------> dnslookup_relay_to_domains router <--------
16993 local_part=peter domain=guildenstern.dyndns.org
16993 checking domains
16993 guildenstern.dyndns.org in 
"@:guildenstern.dyndns.org:localhost:tranquillity.lan:ariel.tranquillity.lan:cordelia.tranquillity.lan:guildenstern.dyndns.org"? 
yes (matched "guildenstern.dyndns.org")
16993 guildenstern.dyndns.org in "! +local_domains : +relay_to_domains"? 
no (matched "! +local_domains")
16993 dnslookup_relay_to_domains router skipped: domains mismatch
16993 --------> send_to_gateway router <--------
16993 local_part=peter domain=guildenstern.dyndns.org
16993 checking domains
16993 cached yes match for +local_domains
16993 cached lookup data = NULL
16993 guildenstern.dyndns.org in "!+local_domains"? no (matched 
"!+local_domains" - cached)
16993 send_to_gateway router skipped: domains mismatch
16993 --------> real_local router <--------
16993 local_part=peter domain=guildenstern.dyndns.org
16993 real_local router skipped: prefix mismatch
16993 --------> spamcheck_router router <--------
16993 local_part=peter domain=guildenstern.dyndns.org
16993 checking for local user
16993 checking "condition"
16993 calling spamcheck_router router
16993 spamcheck_router router called for peter@???
16993   domain = guildenstern.dyndns.org
16993 set transport spamcheck
16993 queued for spamcheck transport: local_part = peter
16993 domain = guildenstern.dyndns.org
16993   errors_to=NULL
16993   domain_data=NULL localpart_data=NULL
16993 routed by spamcheck_router router
16993   envelope to: peter@???
16993   transport: spamcheck
16993 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
16993 After routing:
16993   Local deliveries:
16993     peter@???
16993   Remote deliveries:
16993   Failed addresses:
16993   Deferred addresses:
16993 search_tidyup called
16993 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
16993 --------> peter@??? <--------
16993 locking /var/spool/exim4/db/retry.lockfile
16993 locked /var/spool/exim4/db/retry.lockfile
16993 opened hints database /var/spool/exim4/db/retry: flags=0
16993 dbfn_read: key=T:peter@???
16993 no retry record exists
16993 search_tidyup called
16994 changed uid/gid: local delivery to peter 
<peter@???> transport=spamcheck
16994   uid=112 gid=112 pid=16994
16994   auxiliary group list: <none>
16994   home=/tmp current=/tmp
16994 set_process_info: 16994 delivering 1CjmR1-0004Q3-JV to peter using 
spamcheck
16994 direct command:
16994   argv[0] = /usr/bin/spamc
16994 direct command after expansion:
16994   argv[0] = /usr/bin/spamc
16994 spamcheck transport entered
16994 direct command:
16994   argv[0] = /usr/sbin/exim4
16994   argv[1] = -oMr
16994   argv[2] = spam-scanned
16994   argv[3] = -bS
16994 direct command after expansion:
16994   argv[0] = /usr/sbin/exim4
16994   argv[1] = -oMr
16994   argv[2] = spam-scanned
16994   argv[3] = -bS
16996 set_process_info: 16996 reading output from |/usr/sbin/exim4 -oMr 
spam-scanned -bS
16994 Writing message to pipe
16994 writing data block fd=9 size=0 timeout=3600
16994 writing data block fd=9 size=36 timeout=3600
16994 writing data block fd=9 size=40 timeout=3600
16994 writing data block fd=9 size=5 timeout=3600
16994 process 16997 running as transport filter: write=10 read=11
16998 writing data block fd=10 size=1372 timeout=3600
16994 process 16998 writing to transport filter
16994 copying from the filter
16991 SMTP<< QUIT
16991 SMTP>> 221 ariel.tranquillity.lan closing connection
16991 LOG: smtp_connection MAIN
16991   SMTP connection from rproxy.gmail.com [64.233.170.204] closed by 
QUIT
16991 search_tidyup called
16988 child 16991 ended: status=0x0
16988 0 SMTP accept processes now running
16988 Listening...
16994 waiting for filter process
16994 waiting for writing process
16994 writing data block fd=9 size=1372 timeout=3600
16994 end of filtering transport writing: yield=1
16994 writing data block fd=9 size=0 timeout=3600
16994 writing data block fd=9 size=2 timeout=3600
16994 spamcheck transport yielded 0
16993 journalling peter@???
16994 search_tidyup called
16993 spamcheck transport returned OK for peter@???
16993 post-process peter@??? (0)
16993 peter@??? delivered
16993 LOG: MAIN
16993   => peter <peter@???> R=spamcheck_router 
T=spamcheck
16993 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
16993 changed uid/gid: post-delivery tidying
16993   uid=112 gid=112 pid=16993
16993   auxiliary group list: <none>
16993 set_process_info: 16993 tidying up after delivering 1CjmR1-0004Q3-JV
16993 Processing retry items
16993 Succeeded addresses:
16993 peter@???: no retry items
16993 Failed addresses:
16993 Deferred addresses:
16993 end of retry processing
16993 LOG: MAIN
16993   Completed
16993 end delivery of 1CjmR1-0004Q3-JV
16993 search_tidyup called
16993 search_tidyup called
16993 >>>>>>>>>>>>>>>> Exim pid=16993 terminating with rc=0 >>>>>>>>>>>>>>>>