Bug#311211: exim4: upgrade to 4.50-6 breaks smtp-auth client config

Matt Walters Matt Walters <walters@cs.umn.edu>, 311211@bugs.debian.org
Tue, 31 May 2005 07:37:18 -0500


On Tue, May 31, 2005 at 08:49:27AM +0200, Marc Haber wrote:
 
> echo foo | sudo /usr/sbin/exim4 -d some.address@example.com
> 
> and send me the resulting output.

$ echo foo | sudo /usr/sbin/exim4 -d walters@cs.umn.edu
Exim version 4.50 uid=0 gid=0 pid=3651 D=fbb95cfd
Berkeley DB: Sleepycat Software: Berkeley DB 4.2.52: (December  3, 2003)
Support for: iconv() IPv6 GnuTLS
Lookups: lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmnz dsearch nis nis0 passwd
Authenticators: cram_md5 plaintext
Routers: accept dnslookup ipliteral manualroute queryprogram redirect
Transports: appendfile/maildir/mailstore autoreply lmtp pipe smtp
Fixed never_users: 0
changed uid/gid: forcing real = effective
  uid=0 gid=0 pid=3651
  auxiliary group list: <none>
configuration file is /var/lib/exim4/config.autogenerated
log selectors = 00000ffc 00020800
trusted user
admin user
changed uid/gid: privilege not needed
  uid=102 gid=102 pid=3651
  auxiliary group list: <none>
user name "root" extracted from gecos field "root"
originator: uid=0 gid=0 login=root name=root
sender address = root@localhost
set_process_info:  3651 accepting a local non-SMTP message from <root@localhost>
Sender: root@localhost
Recipients:
  walters@cs.umn.edu
search_tidyup called
>>Headers received:

address match: subject=root@localhost pattern=*@+local_domains
localhost in "@:localhost"? yes (matched "localhost")
localhost in "+local_domains"? yes (matched "+local_domains")
root@localhost in "*@+local_domains"? yes (matched "*@+local_domains")
search_open: lsearch "/etc/email-addresses"
search_find: file="/etc/email-addresses"
  key="root" partial=-1 affix=NULL starflags=0
LRU list:
  :/etc/email-addresses
  End
internal_search_find: file="/etc/email-addresses"
  type=lsearch key="root"
file lookup required for root
  in /etc/email-addresses
lookup failed
address match: subject=root@localhost pattern=*@localhost
localhost in "localhost"? yes (matched "localhost")
root@localhost in "*@localhost"? yes (matched "*@localhost")
search_open: lsearch "/etc/email-addresses"
  cached open
search_find: file="/etc/email-addresses"
  key="root" partial=-1 affix=NULL starflags=0
LRU list:
  :/etc/email-addresses
  End
internal_search_find: file="/etc/email-addresses"
  type=lsearch key="root"
cached data used for lookup of root
  in /etc/email-addresses
lookup failed
rewritten sender = root@localhost
rewrite_one_header: type=F:
  From: root <root@localhost>
address match: subject=root@localhost pattern=*@+local_domains
localhost in "@:localhost"? yes (matched "localhost")
localhost in "+local_domains"? yes (matched "+local_domains")
root@localhost in "*@+local_domains"? yes (matched "*@+local_domains")
search_open: lsearch "/etc/email-addresses"
  cached open
search_find: file="/etc/email-addresses"
  key="root" partial=-1 affix=NULL starflags=0
LRU list:
  :/etc/email-addresses
  End
internal_search_find: file="/etc/email-addresses"
  type=lsearch key="root"
cached data used for lookup of root
  in /etc/email-addresses
lookup failed
address match: subject=root@localhost pattern=*@localhost
localhost in "localhost"? yes (matched "localhost")
root@localhost in "*@localhost"? yes (matched "*@localhost")
search_open: lsearch "/etc/email-addresses"
  cached open
search_find: file="/etc/email-addresses"
  key="root" partial=-1 affix=NULL starflags=0
LRU list:
  :/etc/email-addresses
  End
internal_search_find: file="/etc/email-addresses"
  type=lsearch key="root"
cached data used for lookup of root
  in /etc/email-addresses
lookup failed
search_tidyup called
>>Headers after rewriting and local additions:
I Message-Id: <E1Dd5uh-0000wt-Ih@localhost.localdomain>
F From: root <root@localhost>
  Date: Tue, 31 May 2005 07:32:07 -0500

Data file written for message 1Dd5uh-0000wt-Ih
>>Generated Received: header line
P Received: from root by localhost.localdomain with local (Exim 4.50)
	id 1Dd5uh-0000wt-Ih
	for walters@cs.umn.edu; Tue, 31 May 2005 07:32:07 -0500
calling local_scan(); timeout=300
local_scan() returned 0 NULL
Writing spool header file
Size of headers = 266
LOG: MAIN
  <= root@localhost U=root P=local S=271
search_tidyup called
exec /usr/sbin/exim4 -d=0xfbb95cfd -Mc 1Dd5uh-0000wt-Ih
Exim version 4.50 uid=102 gid=102 pid=3652 D=fbb95cfd
Berkeley DB: Sleepycat Software: Berkeley DB 4.2.52: (December  3, 2003)
Support for: iconv() IPv6 GnuTLS
Lookups: lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmnz dsearch nis nis0 passwd
Authenticators: cram_md5 plaintext
Routers: accept dnslookup ipliteral manualroute queryprogram redirect
Transports: appendfile/maildir/mailstore autoreply lmtp pipe smtp
Fixed never_users: 0
changed uid/gid: forcing real = effective
  uid=0 gid=102 pid=3652
  auxiliary group list: <none>
search_tidyup called
>>>>>>>>>>>>>>>> Exim pid=3651 terminating with rc=0 >>>>>>>>>>>>>>>>
configuration file is /var/lib/exim4/config.autogenerated
log selectors = 00000ffc 00020800
trusted user
admin user
skipping ACL configuration - not needed
set_process_info:  3652 delivering specified messages
set_process_info:  3652 delivering 1Dd5uh-0000wt-Ih
reading spool file 1Dd5uh-0000wt-Ih-H
user=root uid=0 gid=0 sender=root@localhost
sender_local=1 ident=root
Non-recipients:
Empty Tree
---- End of tree ----
recipients_count=1
body_linecount=1 message_linecount=6
Delivery address list:
  walters@cs.umn.edu 
locking /var/spool/exim4/db/retry.lockfile
locked /var/spool/exim4/db/retry.lockfile
opened hints database /var/spool/exim4/db/retry: flags=0
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
Considering: walters@cs.umn.edu
unique = walters@cs.umn.edu
dbfn_read: key=R:cs.umn.edu
dbfn_read: key=R:walters@cs.umn.edu
no domain retry record
no address retry record
walters@cs.umn.edu: queued for routing
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
routing walters@cs.umn.edu
--------> hubbed_hosts router <--------
local_part=walters domain=cs.umn.edu
checking domains
expansion of "${if exists{/etc/exim4/hubbed_hosts}{partial-lsearch;/etc/exim4/hubbed_hosts}fail}" forced failure: assume not in this list
hubbed_hosts router skipped: domains mismatch
--------> smarthost router <--------
local_part=walters domain=cs.umn.edu
checking domains
cs.umn.edu in "@:localhost"? no (end of list)
cs.umn.edu in "! +local_domains"? yes (end of list)
R: smarthost for walters@cs.umn.edu
calling smarthost router
smarthost router called for walters@cs.umn.edu
  domain = cs.umn.edu
route_item = * smtp.umn.edu byname
cs.umn.edu in "*"? yes (matched "*")
original list of hosts = "smtp.umn.edu" options = byname
expanded list of hosts = "smtp.umn.edu" options = byname
set transport remote_smtp_smarthost
finding IP address for smtp.umn.edu
calling host_find_byname
gethostbyname2(af=inet6) returned 4 (NO_DATA)
fully qualified name = smtp.umn.edu
gethostbyname2 looked up these IP addresses:
  name=smtp.umn.edu address=160.94.160.48
  name=smtp.umn.edu address=160.94.23.48
  name=smtp.umn.edu address=160.94.128.48
queued for remote_smtp_smarthost transport: local_part = walters
domain = cs.umn.edu
  errors_to=NULL
  domain_data=NULL localpart_data=NULL
routed by smarthost router
  envelope to: walters@cs.umn.edu
  transport: remote_smtp_smarthost
  host smtp.umn.edu [160.94.160.48]
  host smtp.umn.edu [160.94.23.48]
  host smtp.umn.edu [160.94.128.48]
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
After routing:
  Local deliveries:
  Remote deliveries:
    walters@cs.umn.edu
  Failed addresses:
  Deferred addresses:
search_tidyup called
>>>>>>>>>>>>>>>> Remote deliveries >>>>>>>>>>>>>>>>
--------> walters@cs.umn.edu <--------
search_tidyup called
changed uid/gid: remote delivery to walters@cs.umn.edu with transport=remote_smtp_smarthost
  uid=102 gid=102 pid=3653
  auxiliary group list: <none>
set_process_info:  3653 delivering 1Dd5uh-0000wt-Ih using remote_smtp_smarthost
T: remote_smtp_smarthost for walters@cs.umn.edu
remote_smtp_smarthost transport entered
  walters@cs.umn.edu
cs.umn.edu in queue_smtp_domains? no (option unset)
checking status of smtp.umn.edu
locking /var/spool/exim4/db/retry.lockfile
locked /var/spool/exim4/db/retry.lockfile
opened hints database /var/spool/exim4/db/retry: flags=0
dbfn_read: key=T:smtp.umn.edu:160.94.160.48
dbfn_read: key=T:smtp.umn.edu:160.94.160.48:1Dd5uh-0000wt-Ih
no host retry record
no message retry record
smtp.umn.edu [160.94.160.48] status = usable
160.94.160.48 in serialize_hosts? no (option unset)
delivering 1Dd5uh-0000wt-Ih to smtp.umn.edu [160.94.160.48] (walters@cs.umn.edu)
set_process_info:  3653 delivering 1Dd5uh-0000wt-Ih to smtp.umn.edu [160.94.160.48] (walters@cs.umn.edu)
set_process_info:  3652 delivering 1Dd5uh-0000wt-Ih: waiting for a remote delivery subprocess to finish
selecting on subprocess pipes
Connecting to smtp.umn.edu [160.94.160.48]:25 ... connected
waiting for data on socket
read response data: size=45
  SMTP<< 220 mtaout-w.tc.umn.edu ESMTP service ready
160.94.160.48 in hosts_avoid_esmtp? no (option unset)
  SMTP>> EHLO localhost.localdomain
waiting for data on socket
read response data: size=106
  SMTP<< 250-mtaout-w.tc.umn.edu Hello c-24-118-199-219.hsd1.mn.comcast.net [24.118.199.219], pleased to meet you
waiting for data on socket
read response data: size=112
         250-8BITMIME
         250-DSN
         250-ENHANCEDSTATUSCODES
         250-EXPN
         250-HELP
         250-SIZE 104857600
         250-STARTTLS
         250 VRFY
160.94.160.48 in hosts_avoid_tls? no (option unset)
  SMTP>> STARTTLS
waiting for data on socket
read response data: size=30
  SMTP<< 220 2.0.0 Ready to start TLS
initializing GnuTLS as a client
read RSA and D-H parameters from file
initialized RSA and D-H parameters
no TLS client certificate is specified
initialized certificate stuff
initialized GnuTLS session
cipher: TLS-1.0:RSA_AES_256_CBC_SHA:32
  SMTP>> EHLO localhost.localdomain
tls_do_write(bfffcd10, 28)
gnutls_record_send(SSL, bfffcd10, 28)
outbytes=28
waiting for data on socket
Calling gnutls_record_recv(811e140, bfffd110, 4096)
read response data: size=106
  SMTP<< 250-mtaout-w.tc.umn.edu Hello c-24-118-199-219.hsd1.mn.comcast.net [24.118.199.219], pleased to meet you
waiting for data on socket
Calling gnutls_record_recv(811e140, bfffd110, 4096)
read response data: size=14
         250-8BITMIME
waiting for data on socket
Calling gnutls_record_recv(811e140, bfffd110, 4096)
read response data: size=31
         250-AUTH LOGIN PLAIN CRAM-MD5
waiting for data on socket
Calling gnutls_record_recv(811e140, bfffd110, 4096)
read response data: size=9
         250-DSN
waiting for data on socket
Calling gnutls_record_recv(811e140, bfffd110, 4096)
read response data: size=25
         250-ENHANCEDSTATUSCODES
waiting for data on socket
Calling gnutls_record_recv(811e140, bfffd110, 4096)
read response data: size=10
         250-EXPN
waiting for data on socket
Calling gnutls_record_recv(811e140, bfffd110, 4096)
read response data: size=10
         250-HELP
waiting for data on socket
Calling gnutls_record_recv(811e140, bfffd110, 4096)
read response data: size=20
         250-SIZE 104857600
waiting for data on socket
Calling gnutls_record_recv(811e140, bfffd110, 4096)
read response data: size=10
         250 VRFY
not using PIPELINING
160.94.160.48 in hosts_require_auth? no (option unset)
gethostbyname2(af=inet6) returned 4 (NO_DATA)
gethostbyname2 looked up these IP addresses:
  name=smtp.umn.edu address=160.94.160.48
  name=smtp.umn.edu address=160.94.23.48
  name=smtp.umn.edu address=160.94.128.48
160.94.160.48 in hosts_try_auth? yes (matched "smtp.umn.edu")
scanning authentication mechanisms
search_open: lsearch "/etc/exim4/passwd.client"
search_find: file="/etc/exim4/passwd.client"
  key="smtp.umn.edu" partial=-1 affix=NULL starflags=1
LRU list:
  :/etc/exim4/passwd.client
  End
internal_search_find: file="/etc/exim4/passwd.client"
  type=lsearch key="smtp.umn.edu"
file lookup required for smtp.umn.edu
  in /etc/exim4/passwd.client
lookup failed
trying to match *
internal_search_find: file="/etc/exim4/passwd.client"
  type=lsearch key="*"
file lookup required for *
  in /etc/exim4/passwd.client
lookup failed
search_open: lsearch "/etc/exim4/passwd.client"
  cached open
search_find: file="/etc/exim4/passwd.client"
  key="smtp.umn.edu" partial=-1 affix=NULL starflags=1
LRU list:
  :/etc/exim4/passwd.client
  End
internal_search_find: file="/etc/exim4/passwd.client"
  type=lsearch key="smtp.umn.edu"
cached data used for lookup of smtp.umn.edu
  in /etc/exim4/passwd.client
lookup failed
trying to match *
internal_search_find: file="/etc/exim4/passwd.client"
  type=lsearch key="*"
cached data used for lookup of *
  in /etc/exim4/passwd.client
lookup failed
cram_md5 authenticator yielded 12
search_open: lsearch "/etc/exim4/passwd.client"
  cached open
search_find: file="/etc/exim4/passwd.client"
  key="smtp.umn.edu" partial=-1 affix=NULL starflags=1
LRU list:
  :/etc/exim4/passwd.client
  End
internal_search_find: file="/etc/exim4/passwd.client"
  type=lsearch key="smtp.umn.edu"
cached data used for lookup of smtp.umn.edu
  in /etc/exim4/passwd.client
lookup failed
trying to match *
internal_search_find: file="/etc/exim4/passwd.client"
  type=lsearch key="*"
cached data used for lookup of *
  in /etc/exim4/passwd.client
lookup failed
plain authenticator yielded 12
  SMTP>> AUTH LOGIN
tls_do_write(bfffcd10, 12)
gnutls_record_send(SSL, bfffcd10, 12)
outbytes=12
waiting for data on socket
Calling gnutls_record_recv(811e140, bfffd110, 4096)
read response data: size=18
  SMTP<< 334 VXNlcm5hbWU6
search_open: lsearch "/etc/exim4/passwd.client"
  cached open
search_find: file="/etc/exim4/passwd.client"
  key="smtp.umn.edu" partial=-1 affix=NULL starflags=1
LRU list:
  :/etc/exim4/passwd.client
  End
internal_search_find: file="/etc/exim4/passwd.client"
  type=lsearch key="smtp.umn.edu"
cached data used for lookup of smtp.umn.edu
  in /etc/exim4/passwd.client
lookup failed
trying to match *
internal_search_find: file="/etc/exim4/passwd.client"
  type=lsearch key="*"
cached data used for lookup of *
  in /etc/exim4/passwd.client
lookup failed
  SMTP>> *
tls_do_write(bfffcd10, 3)
gnutls_record_send(SSL, bfffcd10, 3)
outbytes=3
waiting for data on socket
Calling gnutls_record_recv(811e140, bfffd110, 4096)
read response data: size=24
  SMTP<< 501 5.0.0 AUTH aborted
login authenticator yielded 12
  SMTP>> MAIL FROM:<root@localhost> SIZE=1302
tls_do_write(bfffcd10, 38)
gnutls_record_send(SSL, bfffcd10, 38)
outbytes=38
waiting for data on socket
Calling gnutls_record_recv(811e140, bfffd110, 4096)
read response data: size=44
  SMTP<< 530 5.0.0 Must issue an AUTH command first
ok=0 send_quit=1 send_rset=1 continue_more=0 yield=0 first_address is not NULL
  SMTP>> QUIT
tls_do_write(bfffcd10, 6)
gnutls_record_send(SSL, bfffcd10, 6)
outbytes=6
tls_close(): shutting down TLS
set_process_info:  3653 delivering 1Dd5uh-0000wt-Ih: just tried smtp.umn.edu [160.94.160.48] for walters@cs.umn.edu: result OK
Leaving remote_smtp_smarthost transport
set_process_info:  3653 delivering 1Dd5uh-0000wt-Ih (just run remote_smtp_smarthost for walters@cs.umn.edu in subprocess)
search_tidyup called
reading pipe for subprocess 3653 (not ended)
read() yielded 5
selecting on subprocess pipes
reading pipe for subprocess 3653 (not ended)
read() yielded 296
Z0 item read
remote delivery process 3653 ended
set_process_info:  3652 delivering 1Dd5uh-0000wt-Ih
post-process walters@cs.umn.edu (2)
LOG: MAIN
  ** walters@cs.umn.edu R=smarthost T=remote_smtp_smarthost: SMTP error from remote mailer after MAIL FROM:<root@localhost> SIZE=1302: host smtp.umn.edu [160.94.160.48]: 530 5.0.0 Must issue an AUTH command first
>>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
changed uid/gid: post-delivery tidying
  uid=102 gid=102 pid=3652
  auxiliary group list: <none>
set_process_info:  3652 tidying up after delivering 1Dd5uh-0000wt-Ih
Processing retry items
Succeeded addresses:
Failed addresses:
walters@cs.umn.edu: no retry items
Deferred addresses:
end of retry processing
processing failed address walters@cs.umn.edu
exec /usr/sbin/exim4 -d=0xfbb95cfd -t -oem -oi -f <> -E1Dd5uh-0000wt-Ih
sending error message to: root@localhost
writing data block fd=8 size=301 timeout=0
Exim version 4.50 uid=102 gid=102 pid=3654 D=fbb95cfd
Berkeley DB: Sleepycat Software: Berkeley DB 4.2.52: (December  3, 2003)
Support for: iconv() IPv6 GnuTLS
Lookups: lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmnz dsearch nis nis0 passwd
Authenticators: cram_md5 plaintext
Routers: accept dnslookup ipliteral manualroute queryprogram redirect
Transports: appendfile/maildir/mailstore autoreply lmtp pipe smtp
Fixed never_users: 0
changed uid/gid: forcing real = effective
  uid=0 gid=102 pid=3654
  auxiliary group list: <none>
configuration file is /var/lib/exim4/config.autogenerated
log selectors = 00000ffc 00020800
trusted user
admin user
changed uid/gid: privilege not needed
  uid=102 gid=102 pid=3654
  auxiliary group list: <none>
originator: uid=102 gid=102 login=Debian-exim name=
sender address = 
set_process_info:  3654 accepting a local non-SMTP message from <>
Sender: 
search_tidyup called
>>Headers received:
X-Failed-Recipients: walters@cs.umn.edu
Auto-Submitted: auto-generated
From: Mail Delivery System <Mailer-Daemon@localhost>
To: root@localhost
Subject: Mail delivery failed: returning message to sender

rewrite_one_header: type=F:
  From: Mail Delivery System <Mailer-Daemon@localhost>
address match: subject=Mailer-Daemon@localhost pattern=*@+local_domains
localhost in "@:localhost"? yes (matched "localhost")
localhost in "+local_domains"? yes (matched "+local_domains")
Mailer-Daemon@localhost in "*@+local_domains"? yes (matched "*@+local_domains")
search_open: lsearch "/etc/email-addresses"
search_find: file="/etc/email-addresses"
  key="Mailer-Daemon" partial=-1 affix=NULL starflags=0
LRU list:
  :/etc/email-addresses
  End
internal_search_find: file="/etc/email-addresses"
  type=lsearch key="Mailer-Daemon"
file lookup required for Mailer-Daemon
  in /etc/email-addresses
lookup failed
address match: subject=Mailer-Daemon@localhost pattern=*@localhost
localhost in "localhost"? yes (matched "localhost")
Mailer-Daemon@localhost in "*@localhost"? yes (matched "*@localhost")
search_open: lsearch "/etc/email-addresses"
  cached open
search_find: file="/etc/email-addresses"
  key="Mailer-Daemon" partial=-1 affix=NULL starflags=0
LRU list:
  :/etc/email-addresses
  End
internal_search_find: file="/etc/email-addresses"
  type=lsearch key="Mailer-Daemon"
cached data used for lookup of Mailer-Daemon
  in /etc/email-addresses
lookup failed
rewrite_one_header: type=T:
  To: root@localhost
search_tidyup called
>>Headers after rewriting and local additions:
  X-Failed-Recipients: walters@cs.umn.edu
  Auto-Submitted: auto-generated
F From: Mail Delivery System <Mailer-Daemon@localhost>
T To: root@localhost
  Subject: Mail delivery failed: returning message to sender
I Message-Id: <E1Dd5ui-0000ww-M6@localhost.localdomain>
  Date: Tue, 31 May 2005 07:32:08 -0500

Data file written for message 1Dd5ui-0000ww-M6
>>Generated Received: header line
P Received: from Debian-exim by localhost.localdomain with local (Exim 4.50)
	id 1Dd5ui-0000ww-M6
	for root@localhost; Tue, 31 May 2005 07:32:08 -0500
calling local_scan(); timeout=300
local_scan() returned 0 NULL
Writing spool header file
Size of headers = 443
LOG: MAIN
  <= <> R=1Dd5uh-0000wt-Ih U=Debian-exim P=local S=1210
search_tidyup called
exec /usr/sbin/exim4 -d=0xfbb95cfd -Mc 1Dd5ui-0000ww-M6
Exim version 4.50 uid=102 gid=102 pid=3655 D=fbb95cfd
Berkeley DB: Sleepycat Software: Berkeley DB 4.2.52: (December  3, 2003)
Support for: iconv() IPv6 GnuTLS
Lookups: lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmnz dsearch nis nis0 passwd
Authenticators: cram_md5 plaintext
Routers: accept dnslookup ipliteral manualroute queryprogram redirect
Transports: appendfile/maildir/mailstore autoreply lmtp pipe smtp
Fixed never_users: 0
changed uid/gid: forcing real = effective
  uid=0 gid=102 pid=3655
  auxiliary group list: <none>
search_tidyup called
>>>>>>>>>>>>>>>> Exim pid=3654 terminating with rc=0 >>>>>>>>>>>>>>>>
Writing spool header file
Size of headers = 266
LOG: MAIN
  Completed
end delivery of 1Dd5uh-0000wt-Ih
search_tidyup called
search_tidyup called
>>>>>>>>>>>>>>>> Exim pid=3652 terminating with rc=0 >>>>>>>>>>>>>>>>
configuration file is /var/lib/exim4/config.autogenerated
log selectors = 00000ffc 00020800
trusted user
admin user
skipping ACL configuration - not needed
set_process_info:  3655 delivering specified messages
set_process_info:  3655 delivering 1Dd5ui-0000ww-M6
reading spool file 1Dd5ui-0000ww-M6-H
user=Debian-exim uid=102 gid=102 sender=
sender_local=0 ident=Debian-exim
Non-recipients:
Empty Tree
---- End of tree ----
recipients_count=1
body_linecount=20 message_linecount=10
Delivery address list:
  root@localhost 
locking /var/spool/exim4/db/retry.lockfile
locked /var/spool/exim4/db/retry.lockfile
opened hints database /var/spool/exim4/db/retry: flags=0
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
Considering: root@localhost
unique = root@localhost
dbfn_read: key=R:localhost
dbfn_read: key=R:root@localhost
no domain retry record
no address retry record
root@localhost: queued for routing
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
routing root@localhost
--------> hubbed_hosts router <--------
local_part=root domain=localhost
checking domains
expansion of "${if exists{/etc/exim4/hubbed_hosts}{partial-lsearch;/etc/exim4/hubbed_hosts}fail}" forced failure: assume not in this list
hubbed_hosts router skipped: domains mismatch
--------> smarthost router <--------
local_part=root domain=localhost
checking domains
localhost in "@:localhost"? yes (matched "localhost")
localhost in "! +local_domains"? no (matched "! +local_domains")
smarthost router skipped: domains mismatch
--------> real_local router <--------
local_part=root domain=localhost
real_local router skipped: prefix mismatch
--------> system_aliases router <--------
local_part=root domain=localhost
checking domains
cached yes match for +local_domains
cached lookup data = NULL
localhost in "+local_domains"? yes (matched "+local_domains" - cached)
R: system_aliases for root@localhost
calling system_aliases router
rda_interpret (string): ${lookup{$local_part}lsearch{/etc/aliases}}
search_open: lsearch "/etc/aliases"
search_find: file="/etc/aliases"
  key="root" partial=-1 affix=NULL starflags=0
LRU list:
  :/etc/aliases
  End
internal_search_find: file="/etc/aliases"
  type=lsearch key="root"
file lookup required for root
  in /etc/aliases
lookup yielded: matt
expanded: matt
file is not a filter file
parse_forward_list: matt
extract item: matt
system_aliases router generated matt@localhost
  errors_to=NULL transport=NULL
  uid=unset gid=unset home=NULL
routed by system_aliases router
  envelope to: root@localhost
  transport: <none>
locking /var/spool/exim4/db/retry.lockfile
locked /var/spool/exim4/db/retry.lockfile
opened hints database /var/spool/exim4/db/retry: flags=0
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
Considering: matt@localhost
unique = matt@localhost
dbfn_read: key=R:localhost
dbfn_read: key=R:matt@localhost
no domain retry record
no address retry record
matt@localhost: queued for routing
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
routing matt@localhost
--------> hubbed_hosts router <--------
local_part=matt domain=localhost
checking domains
expansion of "${if exists{/etc/exim4/hubbed_hosts}{partial-lsearch;/etc/exim4/hubbed_hosts}fail}" forced failure: assume not in this list
hubbed_hosts router skipped: domains mismatch
--------> smarthost router <--------
local_part=matt domain=localhost
checking domains
localhost in "@:localhost"? yes (matched "localhost")
localhost in "! +local_domains"? no (matched "! +local_domains")
smarthost router skipped: domains mismatch
--------> real_local router <--------
local_part=matt domain=localhost
real_local router skipped: prefix mismatch
--------> system_aliases router <--------
local_part=matt domain=localhost
checking domains
cached yes match for +local_domains
cached lookup data = NULL
localhost in "+local_domains"? yes (matched "+local_domains" - cached)
R: system_aliases for matt@localhost
calling system_aliases router
rda_interpret (string): ${lookup{$local_part}lsearch{/etc/aliases}}
search_open: lsearch "/etc/aliases"
  cached open
search_find: file="/etc/aliases"
  key="matt" partial=-1 affix=NULL starflags=0
LRU list:
  :/etc/aliases
  End
internal_search_find: file="/etc/aliases"
  type=lsearch key="matt"
file lookup required for matt
  in /etc/aliases
lookup failed
expanded: 
file is not a filter file
parse_forward_list: 
system_aliases router declined for matt@localhost
--------> userforward router <--------
local_part=matt domain=localhost
checking domains
cached yes match for +local_domains
cached lookup data = NULL
localhost in "+local_domains"? yes (matched "+local_domains" - cached)
checking for local user
R: userforward for matt@localhost
calling userforward router
rda_interpret (file): $home/.forward
expanded: /home/matt/.forward
stat(/home/matt/.)=0
/home/matt/.forward does not exist
userforward router declined for matt@localhost
--------> procmail router <--------
local_part=matt domain=localhost
checking domains
cached yes match for +local_domains
cached lookup data = NULL
localhost in "+local_domains"? yes (matched "+local_domains" - cached)
checking for local user
finduser used cached passwd data for matt
R: procmail for matt@localhost
checking require_files
finduser used cached passwd data for matt
check subsequent files for access by matt
file check: ${if exists{/etc/procmailrc}{/etc/procmailrc}{${home}/.procmailrc}}
expanded file: /home/matt/.procmailrc
stat() yielded 0
route_check_access(/home/matt/.procmailrc,1000,1000,4)
stat /arc
stat /arc/matt
stat /arc/matt/etc
stat /arc/matt/etc/procmailrc
route_check_access() succeeded
file check: +/usr/bin/procmail
expanded file: /usr/bin/procmail
stat() yielded 0
route_check_access(/usr/bin/procmail,1000,1000,4)
stat /usr
stat /usr/bin
stat /usr/bin/procmail
route_check_access() succeeded
calling procmail router
procmail router called for matt@localhost
  domain = localhost
set transport procmail_pipe
queued for procmail_pipe transport: local_part = matt
domain = localhost
  errors_to=NULL
  domain_data=NULL localpart_data=NULL
routed by procmail router
  envelope to: matt@localhost
  transport: procmail_pipe
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
After routing:
  Local deliveries:
    matt@localhost
  Remote deliveries:
  Failed addresses:
  Deferred addresses:
search_tidyup called
>>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
--------> matt@localhost <--------
locking /var/spool/exim4/db/retry.lockfile
locked /var/spool/exim4/db/retry.lockfile
opened hints database /var/spool/exim4/db/retry: flags=0
dbfn_read: key=T:matt@localhost
no retry record exists
search_tidyup called
changed uid/gid: local delivery to matt <matt@localhost> transport=procmail_pipe
  uid=1000 gid=1000 pid=3656
  auxiliary group list: <none>
  home=/home/matt current=/home/matt
set_process_info:  3656 delivering 1Dd5ui-0000ww-M6 to matt using procmail_pipe
T: procmail_pipe for matt@localhost
procmail_pipe transport entered
direct command:
  argv[0] = /usr/bin/procmail
direct command after expansion:
  argv[0] = /usr/bin/procmail
set_process_info:  3658 reading output from |/usr/bin/procmail
Writing message to pipe
writing data block fd=8 size=44 timeout=3600
writing data block fd=8 size=1301 timeout=3600
writing data block fd=8 size=1 timeout=3600
procmail_pipe transport yielded 0
journalling matt@localhost
search_tidyup called
procmail_pipe transport returned OK for matt@localhost
post-process matt@localhost (0)
matt@localhost delivered
root@localhost: children all complete
LOG: MAIN
  => matt <root@localhost> R=procmail T=procmail_pipe
>>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
changed uid/gid: post-delivery tidying
  uid=102 gid=102 pid=3655
  auxiliary group list: <none>
set_process_info:  3655 tidying up after delivering 1Dd5ui-0000ww-M6
Processing retry items
Succeeded addresses:
matt@localhost: no retry items
root@localhost: no retry items
root@localhost: no retry items
Failed addresses:
Deferred addresses:
end of retry processing
LOG: MAIN
  Completed
end delivery of 1Dd5ui-0000ww-M6
search_tidyup called
search_tidyup called
>>>>>>>>>>>>>>>> Exim pid=3655 terminating with rc=0 >>>>>>>>>>>>>>>>