Re: [exim] Problem with Maildir delivery

トップ ページ
このメッセージを削除
このメッセージに返信
著者: Peter Whysall
日付:  
To: exim-users
題目: Re: [exim] Problem with Maildir delivery
Andrew Nimmo wrote:
> which debug option(s) did u specifiy with exim? could you re-run with
> -d+deliver


I added "-d -v" to QUEUERUNNEROPTIONS in /etc/default/exim4 (I'm using
Ubuntu Linux 4.10 Warty Warthog)

A further piece of information is that .forward consists of the single
line "|/usr/bin/maildrop" for all users. My understanding at the moment
is that .forward is executed when Exim has done everything it needs to do.

I've re-run the delivery with the option you've asked for. Here's the
result.

Restarting MTA: Exim version 4.34 uid=0 gid=0 pid=17421 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=17421
   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
17421 listening on all interfaces (IPv6) port 25
17421 listening on all interfaces (IPv4) port 25
17421 pid written to /var/run/exim4/exim.pid
17421 changed uid/gid: running as a daemon
17421   uid=112 gid=112 pid=17421
17421   auxiliary group list: 112
17421 LOG: MAIN
17421   exim 4.34 daemon started: pid=17421, -q30m, listening for SMTP 
on port 25 (IPv6 and IPv4)
17421 set_process_info: 17421 daemon: -q30m, listening for SMTP on port 
25 (IPv6 and IPv4)
17421 daemon running with uid=112 gid=112 euid=112 egid=112
17421 SIGALRM received
17422 Starting queue-runner: pid 17422
17422 exec /usr/sbin/exim4 -q
17421 1 queue-runner process running
17421 Listening...
17421 Connection request from 64.233.170.194 port 12009
17421 search_tidyup called
17424 host in rfc1413_hosts? yes (matched "*")
17424 doing ident callback
17421 1 SMTP accept process running
17421 child 17422 ended: status=0x0
17421 0 queue-runner processes now running
17421 Listening...
17424 ident connection to 64.233.170.194 failed: Connection timed out
17424 sender_fullhost = [64.233.170.194]
17424 sender_rcvhost = [64.233.170.194]
17424 Process 17424 is handling incoming connection from [64.233.170.194]
17424 host in host_lookup? yes (matched "*")
17424 looking up host name for 64.233.170.194
17424 DNS lookup of 194.170.233.64.in-addr.arpa (PTR) succeeded
17424 IP address lookup yielded rproxy.gmail.com
17424 gethostbyname2(af=inet6) returned 1 (HOST_NOT_FOUND)
17424 gethostbyname2 looked up these IP addresses:
17424   name=rproxy.gmail.com address=64.233.170.203
17424   name=rproxy.gmail.com address=64.233.170.204
17424   name=rproxy.gmail.com address=64.233.170.205
17424   name=rproxy.gmail.com address=64.233.170.206
17424   name=rproxy.gmail.com address=64.233.170.207
17424   name=rproxy.gmail.com address=64.233.170.192
17424   name=rproxy.gmail.com address=64.233.170.193
17424   name=rproxy.gmail.com address=64.233.170.194
17424 checking addresses for rproxy.gmail.com
17424   64.233.170.203
17424   64.233.170.204
17424   64.233.170.205
17424   64.233.170.206
17424   64.233.170.207
17424   64.233.170.192
17424   64.233.170.193
17424   64.233.170.194 OK
17424 sender_fullhost = rproxy.gmail.com [64.233.170.194]
17424 sender_rcvhost = rproxy.gmail.com ([64.233.170.194])
17424 set_process_info: 17424 handling incoming connection from 
rproxy.gmail.com [64.233.170.194]
17424 host in host_reject_connection? no (option unset)
17424 gethostbyname2(af=inet6) returned 1 (HOST_NOT_FOUND)
17424 gethostbyname2 looked up these IP addresses:
17424   name=ariel address=10.200.1.50
17424 host in sender_unqualified_hosts? no (end of list)
17424 gethostbyname2(af=inet6) returned 1 (HOST_NOT_FOUND)
17424 gethostbyname2 looked up these IP addresses:
17424   name=ariel address=10.200.1.50
17424 host in recipient_unqualified_hosts? no (end of list)
17424 host in helo_verify_hosts? no (option unset)
17424 host in helo_try_verify_hosts? no (option unset)
17424 host in helo_accept_junk_hosts? no (option unset)
17424 SMTP>> 220 ariel.tranquillity.lan ESMTP Exim 4.34 Wed, 29 Dec 2004 
23:47:30 +0000
17424 Process 17424 is ready for new message
17424 smtp_setup_msg entered
17424 SMTP<< EHLO rproxy.gmail.com
17424 sender_fullhost = rproxy.gmail.com [64.233.170.194]
17424 sender_rcvhost = rproxy.gmail.com ([64.233.170.194])
17424 set_process_info: 17424 handling incoming connection from 
rproxy.gmail.com [64.233.170.194]
17424 host in pipelining_advertise_hosts? yes (matched "*")
17424 host in auth_advertise_hosts? yes (matched "*")
17424 host in tls_advertise_hosts? no (option unset)
17424 SMTP>> 250-ariel.tranquillity.lan Hello rproxy.gmail.com 
[64.233.170.194]
17424 250-SIZE 52428800
17424 250-PIPELINING
17424 250 HELP
17424 SMTP<< MAIL FROM:<peter.whysall@???>
17424 SMTP>> 250 OK
17424 SMTP<< RCPT TO:<peter@???>
17424 using ACL "acl_check_rcpt"
17424 processing "accept"
17424 check hosts = :
17424 host in ":"? no (end of list)
17424 accept: condition test failed
17424 processing "deny"
17424 check domains = +local_domains
17424 guildenstern.dyndns.org in 
"@:guildenstern.dyndns.org:localhost:tranquillity.lan:ariel.tranquillity.lan:cordelia.tranquillity.lan:guildenstern.dyndns.org"? 
yes (matched "guildenstern.dyndns.org")
17424 guildenstern.dyndns.org in "+local_domains"? yes (matched 
"+local_domains")
17424 check local_parts = ^[.] : ^.*[@%!/|]
17424 peter in "^[.] : ^.*[@%!/|]"? no (end of list)
17424 deny: condition test failed
17424 processing "deny"
17424 check domains = !+local_domains
17424 cached yes match for +local_domains
17424 cached lookup data = NULL
17424 guildenstern.dyndns.org in "!+local_domains"? no (matched 
"!+local_domains" - cached)
17424 deny: condition test failed
17424 processing "accept"
17424 check local_parts = postmaster
17424 peter in "postmaster"? no (end of list)
17424 accept: condition test failed
17424 processing "deny"
17424 check !acl = acl_whitelist_local_deny
17424 using ACL "acl_whitelist_local_deny"
17424 processing "accept"
17424 check hosts = ${if 
exists{/etc/exim4/local_host_whitelist}{/etc/exim4/local_host_whitelist}{}}
17424 host in ""? no (end of list)
17424 accept: condition test failed
17424 processing "accept"
17424 check senders = ${if 
exists{/etc/exim4/local_sender_whitelist}{/etc/exim4/local_sender_whitelist}{}}
17424 peter.whysall@??? in ""? no (end of list)
17424 accept: condition test failed
17424 end of ACL "acl_whitelist_local_deny": implicit DENY
17424 check senders = ${if 
exists{/etc/exim4/local_sender_blacklist}{/etc/exim4/local_sender_blacklist}{}}
17424 peter.whysall@??? in ""? no (end of list)
17424 deny: condition test failed
17424 processing "deny"
17424 check !acl = acl_whitelist_local_deny
17424 using ACL "acl_whitelist_local_deny"
17424 processing "accept"
17424 check hosts = ${if 
exists{/etc/exim4/local_host_whitelist}{/etc/exim4/local_host_whitelist}{}}
17424 host in ""? no (end of list)
17424 accept: condition test failed
17424 processing "accept"
17424 check senders = ${if 
exists{/etc/exim4/local_sender_whitelist}{/etc/exim4/local_sender_whitelist}{}}
17424 peter.whysall@??? in ""? no (end of list)
17424 accept: condition test failed
17424 end of ACL "acl_whitelist_local_deny": implicit DENY
17424 check hosts = ${if 
exists{/etc/exim4/local_host_blacklist}{/etc/exim4/local_host_blacklist}{}}
17424 host in ""? no (end of list)
17424 deny: condition test failed
17424 processing "accept"
17424 check domains = +local_domains
17424 cached yes match for +local_domains
17424 cached lookup data = NULL
17424 guildenstern.dyndns.org in "+local_domains"? yes (matched 
"+local_domains" - cached)
17424 check verify = recipient
17424 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
17424 Verifying peter@???
17424 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
17424 Considering peter@???
17424 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
17424 routing peter@???
17424 --------> hubbed_hosts router <--------
17424 local_part=peter domain=guildenstern.dyndns.org
17424 checking domains
17424 expansion of "${if 
exists{/etc/exim4/hubbed_hosts}{partial-lsearch;/etc/exim4/hubbed_hosts}fail}" 
forced failure: assume not in this list
17424 hubbed_hosts router skipped: domains mismatch
17424 --------> dnslookup_relay_to_domains router <--------
17424 local_part=peter domain=guildenstern.dyndns.org
17424 checking domains
17424 cached yes match for +local_domains
17424 cached lookup data = NULL
17424 guildenstern.dyndns.org in "! +local_domains : +relay_to_domains"? 
no (matched "! +local_domains" - cached)
17424 dnslookup_relay_to_domains router skipped: domains mismatch
17424 --------> send_to_gateway router <--------
17424 local_part=peter domain=guildenstern.dyndns.org
17424 checking domains
17424 cached yes match for +local_domains
17424 cached lookup data = NULL
17424 guildenstern.dyndns.org in "!+local_domains"? no (matched 
"!+local_domains" - cached)
17424 send_to_gateway router skipped: domains mismatch
17424 --------> real_local router <--------
17424 local_part=peter domain=guildenstern.dyndns.org
17424 real_local router skipped: prefix mismatch
17424 --------> spamcheck_router router <--------
17424 local_part=peter domain=guildenstern.dyndns.org
17424 spamcheck_router router skipped: verify 2 0 0
17424 --------> system_aliases router <--------
17424 local_part=peter domain=guildenstern.dyndns.org
17424 checking domains
17424 cached yes match for +local_domains
17424 cached lookup data = NULL
17424 guildenstern.dyndns.org in "+local_domains"? yes (matched 
"+local_domains" - cached)
17424 R: system_aliases for peter@???
17424 calling system_aliases router
17424 rda_interpret (string): ${lookup{$local_part}lsearch{/etc/aliases}}
17424 search_open: lsearch "/etc/aliases"
17424 search_find: file="/etc/aliases"
17424   key="peter" partial=-1 affix=NULL starflags=0
17424 LRU list:
17424   :/etc/aliases
17424   End
17424 internal_search_find: file="/etc/aliases"
17424   type=lsearch key="peter"
17424 file lookup required for peter
17424   in /etc/aliases
17424 lookup failed
17424 expanded:
17424 file is not a filter file
17424 parse_forward_list:
17424 system_aliases router declined for peter@???
17424 --------> mailman_router router <--------
17424 local_part=peter domain=guildenstern.dyndns.org
17424 checking domains
17424 guildenstern.dyndns.org in "guildenstern.dyndns.org"? yes (matched 
"guildenstern.dyndns.org")
17424 guildenstern.dyndns.org in "+mm_domains"? yes (matched "+mm_domains")
17424 checking require_files
17424 file check: /var/lib/mailman/lists/${lc:$local_part}/config.pck
17424 expanded file: /var/lib/mailman/lists/peter/config.pck
17424 stat() yielded -1
17424 errno = 2
17424 mailman_router router skipped: file check
17424 --------> userforward router <--------
17424 local_part=peter domain=guildenstern.dyndns.org
17424 userforward router skipped: verify 2 0 0
17424 --------> procmail router <--------
17424 local_part=peter domain=guildenstern.dyndns.org
17424 procmail router skipped: verify 2 0 0
17424 --------> maildrop router <--------
17424 local_part=peter domain=guildenstern.dyndns.org
17424 maildrop router skipped: verify 2 0 0
17424 --------> local_user router <--------
17424 local_part=peter domain=guildenstern.dyndns.org
17424 checking domains
17424 cached yes match for +local_domains
17424 cached lookup data = NULL
17424 guildenstern.dyndns.org in "+local_domains"? yes (matched 
"+local_domains" - cached)
17424 checking local_parts
17424 peter in "! root"? yes (end of list)
17424 checking for local user
17424 R: local_user for peter@???
17424 calling local_user router
17424 local_user router called for peter@???
17424   domain = guildenstern.dyndns.org
17424 set transport maildir_home
17424 queued for maildir_home transport: local_part = peter
17424 domain = guildenstern.dyndns.org
17424   errors_to=NULL
17424   domain_data=NULL localpart_data=NULL
17424 routed by local_user router
17424   envelope to: peter@???
17424   transport: maildir_home
17424 ----------- end verify ------------
17424 accept: condition test succeeded
17424 SMTP>> 250 Accepted
17424 SMTP<< DATA
17424 SMTP>> 354 Enter message, ending with "." on a line by itself
17424 search_tidyup called
17424 host in ignore_fromline_hosts? no (option unset)
17424 >>Headers received:
17424 Received: by rproxy.gmail.com with SMTP id 34so114512rns
17424         for <peter@???>; Wed, 29 Dec 2004 
15:46:58 -0800 (PST)
17424 DomainKey-Signature: a=rsa-sha1; q=dns; c=nofws;
17424         s=beta; d=gmail.com;
17424 
h=received:message-id:date:from:user-agent:x-accept-language:mime-version:to:subject:content-type:content-transfer-encoding;
17424 
b=CIig7TQD3cQNGP4FqNGjQYjnxfWLFi2bja/fw6zn20YP0JkVbg0gDuJohxjKoUQ46g+JRL2RGrSYU/xwMMC0JYeoIs4LqCMc0b/nZYa8nN/tTkOgqhyo+s56phB/kvBY4ZI3If4adU4e0pLE2uZu1pOAlrgC5vJ1Qxj3DTh+Q84=
17424 Received: by 10.38.206.62 with SMTP id d62mr322002rng;
17424         Wed, 29 Dec 2004 15:46:58 -0800 (PST)
17424 Return-Path: <peter.whysall@???>
17424 Received: from ?10.200.1.2? ([82.9.221.19])
17424         by smtp.gmail.com with ESMTP id 
62sm12863rna.2004.12.29.15.46.57;
17424         Wed, 29 Dec 2004 15:46:58 -0800 (PST)
17424 Message-ID: <41D341F2.9050303@???>
17424 Date: Wed, 29 Dec 2004 23:46:58 +0000
17424 From: Peter Whysall <peter.whysall@???>
17424 User-Agent: Mozilla Thunderbird 1.0 (Windows/20041206)
17424 X-Accept-Language: en-us, en
17424 MIME-Version: 1.0
17424 To:  peter@???
17424 Subject: MAIL ROUTING TEST MESSAGE
17424 Content-Type: text/plain; charset=ISO-8859-1; format=flowed
17424 Content-Transfer-Encoding: 7bit
17424
17424 address match: subject=peter.whysall@??? 
pattern=*@+local_domains
17424 gmail.com in 
"@:guildenstern.dyndns.org:localhost:tranquillity.lan:ariel.tranquillity.lan:cordelia.tranquillity.lan:guildenstern.dyndns.org"? 
no (end of list)
17424 gmail.com in "+local_domains"? no (end of list)
17424 peter.whysall@??? in "*@+local_domains"? no (end of list)
17424 address match: subject=peter.whysall@??? 
pattern=*@+local_domains
17424 gmail.com in 
"@:guildenstern.dyndns.org:localhost:tranquillity.lan:ariel.tranquillity.lan:cordelia.tranquillity.lan:guildenstern.dyndns.org"? 
no (end of list)
17424 gmail.com in "+local_domains"? no (end of list)
17424 peter.whysall@??? in "*@+local_domains"? no (end of list)
17424 address match: subject=peter.whysall@??? 
pattern=*@*.tranquillity.lan
17424 gmail.com in "*.tranquillity.lan"? no (end of list)
17424 peter.whysall@??? in "*@*.tranquillity.lan"? no (end of list)
17424 rewritten sender = peter.whysall@???
17424 rewrite_one_header: type=F:
17424   From: Peter Whysall <peter.whysall@???>
17424 address match: subject=peter.whysall@??? 
pattern=*@+local_domains
17424 gmail.com in 
"@:guildenstern.dyndns.org:localhost:tranquillity.lan:ariel.tranquillity.lan:cordelia.tranquillity.lan:guildenstern.dyndns.org"? 
no (end of list)
17424 gmail.com in "+local_domains"? no (end of list)
17424 peter.whysall@??? in "*@+local_domains"? no (end of list)
17424 address match: subject=peter.whysall@??? 
pattern=*@+local_domains
17424 gmail.com in 
"@:guildenstern.dyndns.org:localhost:tranquillity.lan:ariel.tranquillity.lan:cordelia.tranquillity.lan:guildenstern.dyndns.org"? 
no (end of list)
17424 gmail.com in "+local_domains"? no (end of list)
17424 peter.whysall@??? in "*@+local_domains"? no (end of list)
17424 address match: subject=peter.whysall@??? 
pattern=*@*.tranquillity.lan
17424 gmail.com in "*.tranquillity.lan"? no (end of list)
17424 peter.whysall@??? in "*@*.tranquillity.lan"? no (end of list)
17424 rewrite_one_header: type=T:
17424   To:  peter@???
17424 search_tidyup called
17424 >>Headers after rewriting and local additions:
17424 P Received: by rproxy.gmail.com with SMTP id 34so114512rns
17424         for <peter@???>; Wed, 29 Dec 2004 
15:46:58 -0800 (PST)
17424   DomainKey-Signature: a=rsa-sha1; q=dns; c=nofws;
17424         s=beta; d=gmail.com;
17424 
h=received:message-id:date:from:user-agent:x-accept-language:mime-version:to:subject:content-type:content-transfer-encoding;
17424 
b=CIig7TQD3cQNGP4FqNGjQYjnxfWLFi2bja/fw6zn20YP0JkVbg0gDuJohxjKoUQ46g+JRL2RGrSYU/xwMMC0JYeoIs4LqCMc0b/nZYa8nN/tTkOgqhyo+s56phB/kvBY4ZI3If4adU4e0pLE2uZu1pOAlrgC5vJ1Qxj3DTh+Q84=
17424 P Received: by 10.38.206.62 with SMTP id d62mr322002rng;
17424         Wed, 29 Dec 2004 15:46:58 -0800 (PST)
17424 * Return-Path: <peter.whysall@???>
17424 P Received: from ?10.200.1.2? ([82.9.221.19])
17424         by smtp.gmail.com with ESMTP id 
62sm12863rna.2004.12.29.15.46.57;
17424         Wed, 29 Dec 2004 15:46:58 -0800 (PST)
17424 I Message-ID: <41D341F2.9050303@???>
17424   Date: Wed, 29 Dec 2004 23:46:58 +0000
17424 F From: Peter Whysall <peter.whysall@???>
17424   User-Agent: Mozilla Thunderbird 1.0 (Windows/20041206)
17424   X-Accept-Language: en-us, en
17424   MIME-Version: 1.0
17424 T To:  peter@???
17424   Subject: MAIL ROUTING TEST MESSAGE
17424   Content-Type: text/plain; charset=ISO-8859-1; format=flowed
17424   Content-Transfer-Encoding: 7bit
17424
17424 Data file written for message 1CjnXO-0004X2-Nk
17424 >>Generated Received: header line
17424 P Received: from rproxy.gmail.com ([64.233.170.194])
17424   by ariel.tranquillity.lan with esmtp (Exim 4.34)
17424   id 1CjnXO-0004X2-Nk
17424   for peter@???; Wed, 29 Dec 2004 23:47:30 +0000
17424 using ACL "acl_check_data"
17424 processing "deny"
17424 check demime = *
17424 check condition = ${if >{$demime_errorlevel}{2}{1}{0}}
17424                 = 0
17424 deny: condition test failed
17424 processing "deny"
17424 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
17424 deny: condition test failed
17424 processing "deny"
17424 check !senders = :
17424 address match: subject=peter.whysall@??? pattern=
17424 gmail.com in ""? no (end of list)
17424 peter.whysall@??? in ":"? no (end of list)
17424 check condition = ${if !def:h_Message-ID: {1}}
17424                 =
17424 deny: condition test failed
17424 processing "accept"
17424 accept: condition test succeeded
17424 unspool_mbox(): unlinking 
'/var/spool/exim4/scan/1CjnXO-0004X2-Nk/1CjnXO-0004X2-Nk.eml'
17424 unspool_mbox(): unlinking 
'/var/spool/exim4/scan/1CjnXO-0004X2-Nk/1CjnXO-0004X2-Nk-00000.com'
17424 calling local_scan(); timeout=300
17424 local_scan() returned 0 NULL
17424 Writing spool header file
17424 Size of headers = 1378
17424 LOG: MAIN
17424   <= peter.whysall@??? H=rproxy.gmail.com [64.233.170.194] 
P=esmtp S=1395 id=41D341F2.9050303@???
17424 SMTP>> 250 OK id=1CjnXO-0004X2-Nk
17424 search_tidyup called
17424 Sender: peter.whysall@???
17424 Recipients:
17424   peter@???
17426 exec /usr/sbin/exim4 -d=0xfbbd5cfd -Mc 1CjnXO-0004X2-Nk
17424 forked delivery process 17426
17424 Process 17424 is ready for new message
17424 smtp_setup_msg entered
17426 Exim version 4.34 uid=112 gid=112 pid=17426 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
17426 changed uid/gid: forcing real = effective
17426   uid=0 gid=112 pid=17426
17426   auxiliary group list: <none>
17426 configuration file is /var/lib/exim4/config.autogenerated
17426 log selectors = 00000ffc 00010400
17426 trusted user
17426 admin user
17426 skipping ACL configuration - not needed
17426 set_process_info: 17426 delivering specified messages
17426 set_process_info: 17426 delivering 1CjnXO-0004X2-Nk
17426 reading spool file 1CjnXO-0004X2-Nk-H
17426 user=root uid=0 gid=0 sender=peter.whysall@???
17426 sender_fullhost = rproxy.gmail.com [64.233.170.194]
17426 sender_rcvhost = rproxy.gmail.com ([64.233.170.194])
17426 sender_local=0 ident=unset
17426 Non-recipients:
17426 Empty Tree
17426 ---- End of tree ----
17426 recipients_count=1
17426 body_linecount=1 message_linecount=25
17426 Delivery address list:
17426   peter@???
17426 locking /var/spool/exim4/db/retry.lockfile
17426 locked /var/spool/exim4/db/retry.lockfile
17426 opened hints database /var/spool/exim4/db/retry: flags=0
17426 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
17426 Considering: peter@???
17426 unique = peter@???
17426 dbfn_read: key=R:guildenstern.dyndns.org
17426 dbfn_read: key=R:peter@???
17426 no domain retry record
17426 no address retry record
17426 peter@???: queued for routing
17426 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
17426 routing peter@???
17426 --------> hubbed_hosts router <--------
17426 local_part=peter domain=guildenstern.dyndns.org
17426 checking domains
17426 expansion of "${if 
exists{/etc/exim4/hubbed_hosts}{partial-lsearch;/etc/exim4/hubbed_hosts}fail}" 
forced failure: assume not in this list
17426 hubbed_hosts router skipped: domains mismatch
17426 --------> dnslookup_relay_to_domains router <--------
17426 local_part=peter domain=guildenstern.dyndns.org
17426 checking domains
17426 guildenstern.dyndns.org in 
"@:guildenstern.dyndns.org:localhost:tranquillity.lan:ariel.tranquillity.lan:cordelia.tranquillity.lan:guildenstern.dyndns.org"? 
yes (matched "guildenstern.dyndns.org")
17426 guildenstern.dyndns.org in "! +local_domains : +relay_to_domains"? 
no (matched "! +local_domains")
17426 dnslookup_relay_to_domains router skipped: domains mismatch
17426 --------> send_to_gateway router <--------
17426 local_part=peter domain=guildenstern.dyndns.org
17426 checking domains
17426 cached yes match for +local_domains
17426 cached lookup data = NULL
17426 guildenstern.dyndns.org in "!+local_domains"? no (matched 
"!+local_domains" - cached)
17426 send_to_gateway router skipped: domains mismatch
17426 --------> real_local router <--------
17426 local_part=peter domain=guildenstern.dyndns.org
17426 real_local router skipped: prefix mismatch
17426 --------> spamcheck_router router <--------
17426 local_part=peter domain=guildenstern.dyndns.org
17426 checking for local user
17426 checking "condition"
17426 calling spamcheck_router router
17426 spamcheck_router router called for peter@???
17426   domain = guildenstern.dyndns.org
17426 set transport spamcheck
17426 queued for spamcheck transport: local_part = peter
17426 domain = guildenstern.dyndns.org
17426   errors_to=NULL
17426   domain_data=NULL localpart_data=NULL
17426 routed by spamcheck_router router
17426   envelope to: peter@???
17426   transport: spamcheck
17426 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
17426 After routing:
17426   Local deliveries:
17426     peter@???
17426   Remote deliveries:
17426   Failed addresses:
17426   Deferred addresses:
17426 search_tidyup called
17426 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
17426 --------> peter@??? <--------
17426 locking /var/spool/exim4/db/retry.lockfile
17426 locked /var/spool/exim4/db/retry.lockfile
17426 opened hints database /var/spool/exim4/db/retry: flags=0
17426 dbfn_read: key=T:peter@???
17426 no retry record exists
17426 search_tidyup called
17427 changed uid/gid: local delivery to peter 
<peter@???> transport=spamcheck
17427   uid=112 gid=112 pid=17427
17427   auxiliary group list: <none>
17427   home=/tmp current=/tmp
17427 set_process_info: 17427 delivering 1CjnXO-0004X2-Nk to peter using 
spamcheck
17427 direct command:
17427   argv[0] = /usr/bin/spamc
17427 direct command after expansion:
17427   argv[0] = /usr/bin/spamc
17427 spamcheck transport entered
17427 direct command:
17427   argv[0] = /usr/sbin/exim4
17427   argv[1] = -oMr
17427   argv[2] = spam-scanned
17427   argv[3] = -bS
17427 direct command after expansion:
17427   argv[0] = /usr/sbin/exim4
17427   argv[1] = -oMr
17427   argv[2] = spam-scanned
17427   argv[3] = -bS
17429 set_process_info: 17429 reading output from |/usr/sbin/exim4 -oMr 
spam-scanned -bS
17427 Writing message to pipe
17427 writing data block fd=9 size=0 timeout=3600
17427 writing data block fd=9 size=36 timeout=3600
17427 writing data block fd=9 size=40 timeout=3600
17427 writing data block fd=9 size=5 timeout=3600
17427 process 17430 running as transport filter: write=10 read=11
17431 writing data block fd=10 size=1395 timeout=3600
17427 process 17431 writing to transport filter
17427 copying from the filter
17424 SMTP<< QUIT
17424 SMTP>> 221 ariel.tranquillity.lan closing connection
17424 LOG: smtp_connection MAIN
17424   SMTP connection from rproxy.gmail.com [64.233.170.194] closed by 
QUIT
17424 search_tidyup called
17421 child 17424 ended: status=0x0
17421 0 SMTP accept processes now running
17421 Listening...
17427 waiting for filter process
17427 waiting for writing process
17427 writing data block fd=9 size=1395 timeout=3600
17427 end of filtering transport writing: yield=1
17427 writing data block fd=9 size=0 timeout=3600
17427 writing data block fd=9 size=2 timeout=3600
17427 spamcheck transport yielded 0
17426 journalling peter@???
17427 search_tidyup called
17426 spamcheck transport returned OK for peter@???
17426 post-process peter@??? (0)
17426 peter@??? delivered
17426 LOG: MAIN
17426   => peter <peter@???> R=spamcheck_router 
T=spamcheck
17426 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
17426 changed uid/gid: post-delivery tidying
17426   uid=112 gid=112 pid=17426
17426   auxiliary group list: <none>
17426 set_process_info: 17426 tidying up after delivering 1CjnXO-0004X2-Nk
17426 Processing retry items
17426 Succeeded addresses:
17426 peter@???: no retry items
17426 Failed addresses:
17426 Deferred addresses:
17426 end of retry processing
17426 LOG: MAIN
17426   Completed
17426 end delivery of 1CjnXO-0004X2-Nk
17426 search_tidyup called
17426 search_tidyup called
17426 >>>>>>>>>>>>>>>> Exim pid=17426 terminating with rc=0 >>>>>>>>>>>>>>>>