Bug#742901: exim4 doesn't send authentication: broken
David Lawyer
dave at lafn.org
Fri Mar 28 19:38:25 UTC 2014
Package: exim4_4.82-5_all
Version: 4.82-5
I have 4 exim4 packages installed for each version. When I updated exim4 from 4.80-4 to 4.82-5, exim broke since it would not output email to my smart-host.
The logs said that the smart-host said "Relaying denied. Proper authentation required." I wrote a test script which ran exim with -d (debug) for both versions (4.80 and 4.82). The debug output is attached as a wdiff file showing the difference between the output for 4.80 and 4.82.
What is observed from looking at this debug output is: After my exim and the smart-host (server) agree on "using PIPELINING" both versions of exim find my name and password. But next they do "scanning authentication mechanisms" quite differenly. 4.80 looks up my password twice more (wasted effort since my passwork has already been found). It then sends authentication to the server
by SMPT>> AUTH PLAIN *****..** and the server responds "OK Authenticated". Then the header FROM: TO: and DATA is sent to the sever.
But 4.82 just skips any authentication and sends FROM: TO: and DATA to the server OK. The server say the sender is OK but then after repeating the TO: address says "Relaying denied. Proper authentation required." It's obvious this is happening because of the failure of 4.82 to send any authentication to the server.
David Lawyer
-------------- next part --------------
Diff results (wdiff) for debugging exim4 4.80 (blue) and 4.82 (red). Use "cat eximdiff | colordiff | most" to view in color.
-----------------------------------------------------------------------------
Exim version [-4.82-] {+4.80+} uid=1000 gid=1000 [-pid=7714-] {+pid=5093+} D=fbb95cfd
Berkeley DB: Berkeley DB [-5.3.28: (September 9, 2013)-] {+5.1.29: (October 25, 2011)+}
Support for: crypteq iconv() IPv6 GnuTLS move_frozen_messages DKIM
Lookups (built-in): lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmjz dbmnz dnsdb 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
Size of off_t: 8
Compiler: GCC [-[4.8.2]-] {+[4.7.0]+}
Library version: GnuTLS: Compile: [-2.12.23-] {+2.12.20+}
Runtime: 2.12.23
Library version: PCRE: Compile: [-8.31-] {+8.30+}
Runtime: 8.31 2012-07-06
Total 13 lookups
WHITELIST_D_MACROS: "OUTGOING"
TRUSTED_CONFIG_LIST: "/etc/exim4/trusted_configs"
changed uid/gid: forcing real = effective
uid=0 gid=1000 [-pid=7714-] {+pid=5093+}
auxiliary group list: <none>
seeking password data for user "uucp": cache not available
getpwnam() succeeded uid=10 gid=10
changed uid/gid: calling tls_validate_require_cipher
uid=107 gid=107 [-pid=7715-] {+pid=5094+}
auxiliary group list: <none>
tls_validate_require_cipher child [-7715-] {+5094+} ended: status=0x0
configuration file is /var/lib/exim4/config.autogenerated
log selectors = 00000ffc [-00632001-] {+00612001+}
cwd=/home/dave 3 args: [-exim -d+auth-] {+/usr/sbin/exim -d+tls+} christine256 at gmail.com
admin user
changed uid/gid: privilege not needed
uid=107 gid=107 [-pid=7714-] {+pid=5093+}
auxiliary group list: 107
seeking password data for user "mail": cache not available
getpwnam() succeeded uid=8 gid=8
user name "David Lawyer" extracted from gecos field "David Lawyer,,,,"
originator: uid=1000 gid=1000 login=dave name=David Lawyer
sender address = dave at daveslinux
set_process_info: [-7714-] {+5093+} accepting a local non-SMTP message from <dave at daveslinux>
Sender: dave at daveslinux
Recipients:
christine256 at gmail.com
search_tidyup called
>>Headers received:
From: dave at lafn.org
To: christine256 at gmail.com
Subject: [-test-]
[--]
[-address match-] test: {+Don't reply+}
{++}
{+address match:+} subject=christine256 at gmail.com pattern=dave at lafn.org
christine256 at gmail.com in "dave at lafn.org"? no (end of list)
address [-match test:-] {+match:+} subject=dave at daveslinux pattern=*@+local_domains
daveslinux in "@:localhost"? yes (matched "@")
daveslinux in "+local_domains"? yes (matched "+local_domains")
dave at daveslinux in "*@+local_domains"? yes (matched "*@+local_domains")
search_open: lsearch "/etc/email-addresses"
search_find: file="/etc/email-addresses"
key="dave" partial=-1 affix=NULL starflags=0
LRU list:
7/etc/email-addresses
End
internal_search_find: file="/etc/email-addresses"
type=lsearch key="dave"
file lookup required for dave
in /etc/email-addresses
lookup yielded: dave at lafn.org
LOG: address_rewrite MAIN
"dave at daveslinux" from env-from rewritten as "dave at lafn.org" by rule 2
address [-match test:-] {+match:+} subject=dave at lafn.org pattern=*@daveslinux
lafn.org in "daveslinux"? no (end of list)
dave at lafn.org in "*@daveslinux"? no (end of list)
rewritten sender = dave at lafn.org
rewrite_one_header: type=F:
From: dave at lafn.org
address [-match test:-] {+match:+} subject=dave at lafn.org pattern=*@+local_domains
lafn.org in "@:localhost"? no (end of list)
lafn.org in "+local_domains"? no (end of list)
dave at lafn.org in "*@+local_domains"? no (end of list)
address [-match test:-] {+match:+} subject=dave at lafn.org pattern=*@daveslinux
lafn.org in "daveslinux"? no (end of list)
dave at lafn.org in "*@daveslinux"? no (end of list)
rewrite_one_header: type=T:
To: christine256 at gmail.com
search_tidyup called
>>Headers after rewriting and local additions:
F From: dave at lafn.org
T To: christine256 at gmail.com
Subject: [-test-] {+test: Don't reply+}
I Message-Id: [-<E1WT43z-00020Q-9d at daveslinux>-] {+<E1WTGtR-0001K9-L7 at daveslinux>+}
* X-rewrote-sender: dave at daveslinux
Date: [-Wed, 26-] {+Thu, 27+} Mar 2014 [-23:46:27-] {+13:27:17+} -0700
Data file written for message [-1WT43z-00020Q-9d-] {+1WTGtR-0001K9-L7+}
>>Generated Received: header line
P Received: from dave by daveslinux with local (Exim [-4.82)-] {+4.80)+}
(envelope-from <dave at lafn.org>)
id [-1WT43z-00020Q-9d-] {+1WTGtR-0001K9-L7+}
for christine256 at gmail.com; [-Wed, 26-] {+Thu, 27+} Mar 2014 [-23:46:28-] {+13:27:17+} -0700
calling local_scan(); timeout=300
local_scan() returned 0 NULL
Writing spool header file
Size of headers = [-314-] {+327+}
LOG: MAIN
<= dave at lafn.org U=dave P=local [-S=320-] {+S=662+}
search_tidyup called
search_tidyup called
[-exec /usr/sbin/exim4 -d=0xfbb95cfd -Mc 1WT43z-00020Q-9d-]
>>>>>>>>>>>>>>>> Exim [-pid=7714-] {+pid=5093+} terminating with rc=0 >>>>>>>>>>>>>>>>
{+exec /usr/sbin/exim4 -d=0xfbb95cfd -Mc 1WTGtR-0001K9-L7+}
Exim version [-4.82-] {+4.80+} uid=107 gid=107 [-pid=7716-] {+pid=5095+} D=fbb95cfd
Berkeley DB: Berkeley DB [-5.3.28: (September 9, 2013)-] {+5.1.29: (October 25, 2011)+}
Support for: crypteq iconv() IPv6 GnuTLS move_frozen_messages DKIM
Lookups (built-in): lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmjz dbmnz dnsdb 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
Size of off_t: 8
Compiler: GCC [-[4.8.2]-] {+[4.7.0]+}
Library version: GnuTLS: Compile: [-2.12.23-] {+2.12.20+}
Runtime: 2.12.23
Library version: PCRE: Compile: [-8.31-] {+8.30+}
Runtime: 8.31 2012-07-06
Total 13 lookups
WHITELIST_D_MACROS: "OUTGOING"
TRUSTED_CONFIG_LIST: "/etc/exim4/trusted_configs"
changed uid/gid: forcing real = effective
uid=0 gid=107 [-pid=7716-] {+pid=5095+}
auxiliary group list: <none>
seeking password data for user "uucp": cache not available
getpwnam() succeeded uid=10 gid=10
changed uid/gid: calling tls_validate_require_cipher
uid=107 gid=107 [-pid=7717-] {+pid=5096+}
auxiliary group list: <none>
tls_validate_require_cipher child [-7717-] {+5096+} ended: status=0x0
configuration file is /var/lib/exim4/config.autogenerated
log selectors = 00000ffc [-00632001-] {+00612001+}
cwd=/var/spool/exim4 4 args: /usr/sbin/exim4 -d=0xfbb95cfd -Mc [-1WT43z-00020Q-9d-] {+1WTGtR-0001K9-L7+}
trusted user
admin user
{+skipping ACL configuration - not needed+}
seeking password data for user "mail": cache not available
getpwnam() succeeded uid=8 gid=8
set_process_info: [-7716-] {+5095+} delivering specified messages
set_process_info: [-7716-] {+5095+} delivering [-1WT43z-00020Q-9d-] {+1WTGtR-0001K9-L7+}
reading spool file [-1WT43z-00020Q-9d-H-] {+1WTGtR-0001K9-L7-H+}
user=dave uid=1000 gid=1000 sender=dave at lafn.org
sender_local=1 ident=dave
Non-recipients:
Empty Tree
---- End of tree ----
recipients_count=1
body_linecount=1 message_linecount=9
Delivery address list:
christine256 at gmail.com
locking /var/spool/exim4/db/retry.lockfile
locked /var/spool/exim4/db/retry.lockfile
EXIM_DBOPEN(/var/spool/exim4/db/retry)
returned from EXIM_DBOPEN
no retry data available
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
Considering: christine256 at gmail.com
unique = christine256 at gmail.com
no domain retry record
no address retry record
christine256 at gmail.com: queued for routing
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
routing christine256 at gmail.com
--------> hubbed_hosts router <--------
local_part=christine256 domain=gmail.com
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=christine256 domain=gmail.com
checking domains
gmail.com in "@:localhost"? no (end of list)
gmail.com in "! +local_domains"? yes (end of list)
R: smarthost for christine256 at gmail.com
calling smarthost router
smarthost router called for christine256 at gmail.com
domain = gmail.com
route_item = * smtp.lafn.org byname
gmail.com in "*"? yes (matched "*")
original list of hosts = "smtp.lafn.org" options = byname
expanded list of hosts = "smtp.lafn.org" options = byname
set transport remote_smtp_smarthost
finding IP address for smtp.lafn.org
calling host_find_byname
gethostbyname2(af=inet6) returned 3 (NO_RECOVERY)
fully qualified name = zoom.lafn.org
gethostbyname2 looked up these IP addresses:
name=zoom.lafn.org address=108.92.93.123
queued for remote_smtp_smarthost transport: local_part = christine256
domain = gmail.com
errors_to=NULL
domain_data=NULL localpart_data=NULL
routed by smarthost router
envelope to: christine256 at gmail.com
transport: remote_smtp_smarthost
host zoom.lafn.org [108.92.93.123]
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
After routing:
Local deliveries:
Remote deliveries:
christine256 at gmail.com
Failed addresses:
Deferred addresses:
search_tidyup called
>>>>>>>>>>>>>>>> Remote deliveries >>>>>>>>>>>>>>>>
--------> christine256 at gmail.com <--------
search_tidyup called
set_process_info: [-7716-] {+5095+} delivering [-1WT43z-00020Q-9d:-] {+1WTGtR-0001K9-L7:+} waiting for a remote delivery subprocess to finish
selecting on subprocess pipes
changed uid/gid: remote delivery to christine256 at gmail.com with transport=remote_smtp_smarthost
uid=107 gid=107 [-pid=7718-] {+pid=5097+}
auxiliary group list: <none>
set_process_info: [-7718-] {+5097+} delivering [-1WT43z-00020Q-9d-] {+1WTGtR-0001K9-L7+} using remote_smtp_smarthost
T: remote_smtp_smarthost for christine256 at gmail.com
remote_smtp_smarthost transport entered
christine256 at gmail.com
gmail.com in queue_smtp_domains? no (option unset)
checking status of zoom.lafn.org
locking /var/spool/exim4/db/retry.lockfile
locked /var/spool/exim4/db/retry.lockfile
EXIM_DBOPEN(/var/spool/exim4/db/retry)
returned from EXIM_DBOPEN
no retry data available
zoom.lafn.org [108.92.93.123] status = usable
108.92.93.123 in serialize_hosts? no (option unset)
delivering [-1WT43z-00020Q-9d-] {+1WTGtR-0001K9-L7+} to zoom.lafn.org [108.92.93.123] (christine256 at gmail.com)
set_process_info: [-7718-] {+5097+} delivering [-1WT43z-00020Q-9d-] {+1WTGtR-0001K9-L7+} to zoom.lafn.org [108.92.93.123] (christine256 at gmail.com)
Connecting to zoom.lafn.org [108.92.93.123]:25 ... connected
waiting for data on socket
read response data: size=87
SMTP<< 220 zoom.lafn.org ESMTP Sendmail 8.14.7/8.14.2; [-Wed, 26-] {+Thu, 27+} Mar 2014 [-23:46:29-] {+13:27:19+} -0700 (PDT)
108.92.93.123 in hosts_avoid_esmtp? no (option unset)
SMTP>> EHLO daveslinux
waiting for data on socket
read response data: [-size=266-] {+size=268+}
SMTP<< 250-zoom.lafn.org Hello [-66-81-120-83.socal.dialup.o1.com [66.81.120.83],-] {+66-81-120-135.socal.dialup.o1.com [66.81.120.135],+} pleased to meet you
250-ENHANCEDSTATUSCODES
250-PIPELINING
250-EXPN
250-VERB
250-8BITMIME
250-SIZE
250-DSN
250-ETRN
250-AUTH GSSAPI LOGIN PLAIN
250-STARTTLS
250-DELIVERBY
250 HELP
108.92.93.123 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
initialising GnuTLS as a client on fd 6
GnuTLS global init required.
initialising GnuTLS client session
Expanding various TLS configuration options for session credentials.
TLS: no client certificate specified; okay
TLS: tls_verify_certificates not set or empty, ignoring
GnuTLS using default session cipher/priority "NORMAL"
WARNING: tls_dh_min_bits far too low, clamping 52 up to 512
Setting D-H prime minimum acceptable bits to 512
TLS: server certificate verification not required
gnutls_handshake was successful
cipher: TLS1.0:DHE_RSA_AES_128_CBC_SHA1:128
Have channel bindings cached for possible auth usage.
SMTP>> EHLO daveslinux
[-tls_do_write(0xbf9384bc,-]
{+tls_do_write(0xbff2abfc,+} 17)
gnutls_record_send(SSL, [-0xbf9384bc,-] {+0xbff2abfc,+} 17)
outbytes=17
waiting for data on socket
Calling [-gnutls_record_recv(0xb88f3a18, 0xbf9398bc,-] {+gnutls_record_recv(0xb8e6e6e8, 0xbff2bffc,+} 4096)
read response data: [-size=252-] {+size=254+}
SMTP<< 250-zoom.lafn.org Hello [-66-81-120-83.socal.dialup.o1.com [66.81.120.83],-] {+66-81-120-135.socal.dialup.o1.com [66.81.120.135],+} pleased to meet you
250-ENHANCEDSTATUSCODES
250-PIPELINING
250-EXPN
250-VERB
250-8BITMIME
250-SIZE
250-DSN
250-ETRN
250-AUTH GSSAPI LOGIN PLAIN
250-DELIVERBY
250 HELP
108.92.93.123 in hosts_avoid_pipelining? no (option unset)
using PIPELINING
108.92.93.123 in hosts_require_auth? no (option unset)
search_open: nwildlsearch "/etc/exim4/passwd.client"
search_find: file="/etc/exim4/passwd.client"
key="zoom.lafn.org" partial=-1 affix=NULL starflags=0
LRU list:
:/etc/exim4/passwd.client
End
internal_search_find: file="/etc/exim4/passwd.client"
type=nwildlsearch key="zoom.lafn.org"
file lookup required for zoom.lafn.org
in /etc/exim4/passwd.client
zoom.lafn.org in "zoom.lafn.org"? yes (matched "zoom.lafn.org")
lookup yielded: dave:vorkut
108.92.93.123 in hosts_try_auth? yes (matched "108.92.93.123")
scanning authentication mechanisms
{+search_open: nwildlsearch "/etc/exim4/passwd.client"+}
{+ cached open+}
{+search_find: file="/etc/exim4/passwd.client"+}
{+ key="zoom.lafn.org" partial=-1 affix=NULL starflags=0+}
{+LRU list:+}
{+ :/etc/exim4/passwd.client+}
{+ End+}
{+internal_search_find: file="/etc/exim4/passwd.client"+}
{+ type=nwildlsearch key="zoom.lafn.org"+}
{+cached data used for lookup of zoom.lafn.org+}
{+ in /etc/exim4/passwd.client+}
{+lookup yielded: dave:vorkut+}
{+search_open: nwildlsearch "/etc/exim4/passwd.client"+}
{+ cached open+}
{+search_find: file="/etc/exim4/passwd.client"+}
{+ key="zoom.lafn.org" partial=-1 affix=NULL starflags=0+}
{+LRU list:+}
{+ :/etc/exim4/passwd.client+}
{+ End+}
{+internal_search_find: file="/etc/exim4/passwd.client"+}
{+ type=nwildlsearch key="zoom.lafn.org"+}
{+cached data used for lookup of zoom.lafn.org+}
{+ in /etc/exim4/passwd.client+}
{+lookup yielded: dave:vorkut+}
{+ SMTP>> AUTH PLAIN ****************+}
{+tls_do_write(0xbff2abfc, 29)+}
{+gnutls_record_send(SSL, 0xbff2abfc, 29)+}
{+outbytes=29+}
{+waiting for data on socket+}
{+Calling gnutls_record_recv(0xb8e6e6e8, 0xbff2bffc, 4096)+}
{+read response data: size=28+}
{+ SMTP<< 235 2.0.0 OK Authenticated+}
plain authenticator yielded [-13-]
[-login authenticator yielded 13-] {+0+}
SMTP>> MAIL FROM:<dave at lafn.org> [-SIZE=1354-] {+SIZE=1696 AUTH=dave at daveslinux+}
SMTP>> RCPT TO:<christine256 at gmail.com>
SMTP>> DATA
[-tls_do_write(0xbf9384bc, 77)-]
{+tls_do_write(0xbff2abfc, 98)+}
gnutls_record_send(SSL, [-0xbf9384bc, 77)-]
[-outbytes=77-] {+0xbff2abfc, 98)+}
{+outbytes=98+}
waiting for data on socket
Calling [-gnutls_record_recv(0xb88f3a18, 0xbf9398bc,-] {+gnutls_record_recv(0xb8e6e6e8, 0xbff2bffc,+} 4096)
read response data: [-size=161-] {+size=142+}
SMTP<< 250 2.1.0 <dave at lafn.org>... Sender ok
SMTP<< [-550 5.7.1-] {+250 2.1.5+} <christine256 at gmail.com>... [-Relaying denied. Proper authentication required.-] {+Recipient ok+}
SMTP<< [-503 5.0.0 Need RCPT (recipient)-]
[-SMTP error from remote mail server after pipelined DATA: host zoom.lafn.org [108.92.93.123]: 503 5.0.0 Need RCPT (recipient)-]
[-error for DATA ignored: pipelining is in use-] {+354 Enter mail, end with "." on a line by itself+}
{+ SMTP>> writing message+} and [-there were no good recipients-] {+terminating "."+}
{+writing data block fd=6 size=677 timeout=300+}
{+tls_do_write(0xb8e5a740, 677)+}
{+gnutls_record_send(SSL, 0xb8e5a740, 677)+}
{+outbytes=677+}
{+waiting for data on socket+}
{+Calling gnutls_record_recv(0xb8e6e6e8, 0xbff2bffc, 4096)+}
{+read response data: size=56+}
{+ SMTP<< 250 2.0.0 s2RKRJFF008163 Message accepted for delivery+}
{+journalling christine256 at gmail.com+}
ok=1 send_quit=1 [-send_rset=1-] {+send_rset=0+} continue_more=0 yield=0 first_address is NULL
108.92.93.123 in hosts_nopass_tls? no (option unset)
transport_check_waiting entered
sequence=1 local_max=500 global_max=-1
locking /var/spool/exim4/db/wait-remote_smtp_smarthost.lockfile
locked /var/spool/exim4/db/wait-remote_smtp_smarthost.lockfile
EXIM_DBOPEN(/var/spool/exim4/db/wait-remote_smtp_smarthost)
returned from EXIM_DBOPEN
opened hints database /var/spool/exim4/db/wait-remote_smtp_smarthost: flags=O_RDWR
dbfn_read: key=zoom.lafn.org
no messages waiting for zoom.lafn.org
SMTP>> QUIT
[-tls_do_write(0xbf9384bc,-]
{+tls_do_write(0xbff2abfc,+} 6)
gnutls_record_send(SSL, [-0xbf9384bc,-] {+0xbff2abfc,+} 6)
outbytes=6
tls_close(): shutting down TLS
set_process_info: [-7718-] {+5097+} delivering [-1WT43z-00020Q-9d:-] {+1WTGtR-0001K9-L7:+} just tried zoom.lafn.org [108.92.93.123] for christine256 at gmail.com: result OK
Leaving remote_smtp_smarthost transport
set_process_info: [-7718-] {+5097+} delivering [-1WT43z-00020Q-9d-] {+1WTGtR-0001K9-L7+} (just run remote_smtp_smarthost for christine256 at gmail.com in subprocess)
search_tidyup called
reading pipe for subprocess [-7718-] {+5097+} (not ended)
read() yielded 5
selecting on subprocess pipes
reading pipe for subprocess [-7718-] {+5097+} (not ended)
read() yielded 129
selecting on subprocess pipes
reading pipe for subprocess [-7718-] {+5097+} (not ended)
read() yielded [-213-] {+47+}
selecting on subprocess pipes
reading pipe for subprocess [-7718-] {+5097+} (not ended)
read() yielded 2
Z0 item read
remote delivery process [-7718-] {+5097+} ended
set_process_info: [-7716-] {+5095+} delivering [-1WT43z-00020Q-9d-] {+1WTGtR-0001K9-L7+}
post-process christine256 at gmail.com [-(2)-] {+(0)+}
{+christine256 at gmail.com delivered+}
LOG: MAIN
[-**-]
{+=>+} christine256 at gmail.com R=smarthost [-T=remote_smtp_smarthost: SMTP error from remote mail server after RCPT TO:<christine256 at gmail.com>: host zoom.lafn.org [108.92.93.123]: 550 5.7.1 <christine256 at gmail.com>... Relaying denied. Proper authentication required.-] {+T=remote_smtp_smarthost H=zoom.lafn.org [108.92.93.123] X=TLS1.0:DHE_RSA_AES_128_CBC_SHA1:128 DN="C=US,ST=California,L=Los Angeles,O=LA Free-Net,OU=ops,CN=smtp.lafn.org,EMAIL=bc979 at lafn.org"+}
>>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
changed uid/gid: post-delivery tidying
uid=107 gid=107 [-pid=7716-] {+pid=5095+}
auxiliary group list: <none>
set_process_info: [-7716-] {+5095+} tidying up after delivering [-1WT43z-00020Q-9d-] {+1WTGtR-0001K9-L7+}
Processing retry items
Succeeded addresses:
[-Failed addresses:-]
christine256 at gmail.com: no retry items
[-Deferred addresses:-]
[-end of retry processing-]
[-processing failed address christine256 at gmail.com-]
[-exec /usr/sbin/exim4 -d=0xfbb95cfd -t -oem -oi -f <> -E1WT43z-00020Q-9d-]
[-sending error message to: dave at lafn.org-]
[-writing data block fd=8 size=349 timeout=0-]
[-Exim version 4.82 uid=107 gid=107 pid=7719 D=fbb95cfd-]
[-Berkeley DB: Berkeley DB 5.3.28: (September 9, 2013)-]
[-Support for: crypteq iconv() IPv6 GnuTLS move_frozen_messages DKIM-]
[-Lookups (built-in): lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmjz dbmnz dnsdb 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-]
[-Size of off_t: 8-]
[-Compiler: GCC [4.8.2]-]
[-Library version: GnuTLS: Compile: 2.12.23-]
[- Runtime: 2.12.23-]
[-Library version: PCRE: Compile: 8.31-]
[- Runtime: 8.31 2012-07-06-]
[-Total 13 lookups-]
[-WHITELIST_D_MACROS: "OUTGOING"-]
[-TRUSTED_CONFIG_LIST: "/etc/exim4/trusted_configs"-]
[-changed uid/gid: forcing real = effective-]
[- uid=0 gid=107 pid=7719-]
[- auxiliary group list: <none>-]
[-seeking password data for user "uucp": cache not available-]
[-getpwnam() succeeded uid=10 gid=10-]
[-changed uid/gid: calling tls_validate_require_cipher-]
[- uid=107 gid=107 pid=7720-]
[- auxiliary group list: <none>-]
[-tls_validate_require_cipher child 7720 ended: status=0x0-]
[-configuration file is /var/lib/exim4/config.autogenerated-]
[-log selectors = 00000ffc 00632001-]
[-cwd=/var/spool/exim4 8 args: /usr/sbin/exim4 -d=0xfbb95cfd -t -oem -oi -f <> -E1WT43z-00020Q-9d-]
[-trusted user-]
[-admin user-]
[-changed uid/gid: privilege not needed-]
[- uid=107 gid=107 pid=7719-]
[- auxiliary group list: 107-]
[-seeking password data for user "mail": cache not available-]
[-getpwnam() succeeded uid=8 gid=8-]
[-originator: uid=107 gid=107 login=Debian-exim name=-]
[-sender address = -]
[-set_process_info: 7719 accepting a local non-SMTP message from <>-]
[-Sender: -]
[-search_tidyup called-]
[->>Headers received:-]
[-X-Failed-Recipients: christine256 at gmail.com-]
[-Auto-Submitted: auto-replied-]
[-From: Mail Delivery System <Mailer-Daemon at daveslinux>-]
[-To: dave at lafn.org-]
[-Subject: Mail delivery failed: returning message to sender-]
[--]
[-address match test: subject=dave at lafn.org pattern=dave at lafn.org-]
[-lafn.org in "lafn.org"? yes (matched "lafn.org")-]
[-dave at lafn.org in "dave at lafn.org"? yes (matched "dave at lafn.org")-]
[-LOG: address_rewrite MAIN-]
[- "dave at lafn.org" from env-to rewritten as "dave at daveslinux" by rule 1-]
[-rewrite_one_header: type=F:-]
[- From: Mail Delivery System <Mailer-Daemon at daveslinux>-]
[-address match test: subject=Mailer-Daemon at daveslinux pattern=*@+local_domains-]
[-daveslinux in "@:localhost"? yes (matched "@")-]
[-daveslinux in "+local_domains"? yes (matched "+local_domains")-]
[-Mailer-Daemon at daveslinux 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:-]
[- 7/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 test: subject=Mailer-Daemon at daveslinux pattern=*@daveslinux-]
[-daveslinux in "daveslinux"? yes (matched "daveslinux")-]
[-Mailer-Daemon at daveslinux in "*@daveslinux"? yes (matched "*@daveslinux")-]
[-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:-]
[- 7/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: dave at lafn.org-]
[-search_tidyup called-]
[->>Headers after rewriting and local additions:-]
[- X-Failed-Recipients: christine256 at gmail.com-]
[- Auto-Submitted: auto-replied-]
[-F From: Mail Delivery System <Mailer-Daemon at daveslinux>-]
[-T To: dave at lafn.org-]
[- Subject: Mail delivery failed: returning message to sender-]
[-I Message-Id: <E1WT45C-00020V-Lq at daveslinux>-]
[-* X-rewrote-original-recipient: dave at lafn.org-]
[- Date: Wed, 26 Mar 2014 23:46:34 -0700-]
[--]
[-Data file written for message 1WT45C-00020V-Lq-]
[->>Generated Received: header line-]
[-P Received: from Debian-exim by daveslinux with local (Exim 4.82)-]
[- id 1WT45C-00020V-Lq-]
[- for dave at daveslinux; Wed, 26 Mar 2014 23:46:34 -0700-]
[-calling local_scan(); timeout=300-]
[-local_scan() returned 0 NULL-]
[-Writing spool header file-]
[-Size of headers = 424-]
[-LOG: MAIN-]
[- <= <> R=1WT43z-00020Q-9d U=Debian-exim P=local S=1289-]
[-search_tidyup called-]
[-search_tidyup called-]
[->>>>>>>>>>>>>>>> Exim pid=7719 terminating with rc=0 >>>>>>>>>>>>>>>>-]
[-exec /usr/sbin/exim4 -d=0xfbb95cfd -Mc 1WT45C-00020V-Lq-]
[-Writing spool header file-]
[-Exim version 4.82 uid=107 gid=107 pid=7721 D=fbb95cfd-]
[-Berkeley DB: Berkeley DB 5.3.28: (September 9, 2013)-]
[-Support for: crypteq iconv() IPv6 GnuTLS move_frozen_messages DKIM-]
[-Lookups (built-in): lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmjz dbmnz dnsdb 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-]
[-Size of off_t: 8-]
[-Compiler: GCC [4.8.2]-]
[-Library version: GnuTLS: Compile: 2.12.23-]
[- Runtime: 2.12.23-]
[-Library version: PCRE: Compile: 8.31-]
[- Runtime: 8.31 2012-07-06-]
[-Total 13 lookups-]
[-WHITELIST_D_MACROS: "OUTGOING"-]
[-TRUSTED_CONFIG_LIST: "/etc/exim4/trusted_configs"-]
[-changed uid/gid: forcing real = effective-]
[- uid=0 gid=107 pid=7721-]
[- auxiliary group list: <none>-]
[-seeking password data for user "uucp": cache not available-]
[-getpwnam() succeeded uid=10 gid=10-]
[-Size of headers = 314-]
[-LOG: MAIN-]
[- Completed-]
[-end delivery of 1WT43z-00020Q-9d-]
[-search_tidyup called-]
[-search_tidyup called-]
[->>>>>>>>>>>>>>>> Exim pid=7716 terminating with rc=0 >>>>>>>>>>>>>>>>-]
[-changed uid/gid: calling tls_validate_require_cipher-]
[- uid=107 gid=107 pid=7722-]
[- auxiliary group list: <none>-]
[-tls_validate_require_cipher child 7722 ended: status=0x0-]
[-configuration file is /var/lib/exim4/config.autogenerated-]
[-log selectors = 00000ffc 00632001-]
[-cwd=/var/spool/exim4 4 args: /usr/sbin/exim4 -d=0xfbb95cfd -Mc 1WT45C-00020V-Lq-]
[-trusted user-]
[-admin user-]
[-seeking password data for user "mail": cache not available-]
[-getpwnam() succeeded uid=8 gid=8-]
[-set_process_info: 7721 delivering specified messages-]
[-set_process_info: 7721 delivering 1WT45C-00020V-Lq-]
[-reading spool file 1WT45C-00020V-Lq-H-]
[-user=Debian-exim uid=107 gid=107 sender=-]
[-sender_local=0 ident=Debian-exim-]
[-Non-recipients:-]
[-Empty Tree-]
[----- End of tree -----]
[-recipients_count=1-]
[-body_linecount=23 message_linecount=10-]
[-Delivery address list:-]
[- dave at daveslinux -]
[-locking /var/spool/exim4/db/retry.lockfile-]
[-locked /var/spool/exim4/db/retry.lockfile-]
[-EXIM_DBOPEN(/var/spool/exim4/db/retry)-]
[-returned from EXIM_DBOPEN-]
[-no retry data available-]
[->>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>-]
[-Considering: dave at daveslinux-]
[-unique = dave at daveslinux-]
[-no domain retry record-]
[-no address retry record-]
[-dave at daveslinux: queued for routing-]
[->>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>-]
[-routing dave at daveslinux-]
[---------> hubbed_hosts router <---------]
[-local_part=dave domain=daveslinux-]
[-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=dave domain=daveslinux-]
[-checking domains-]
[-daveslinux in "@:localhost"? yes (matched "@")-]
[-daveslinux in "! +local_domains"? no (matched "! +local_domains")-]
[-smarthost router skipped: domains mismatch-]
[---------> real_local router <---------]
[-local_part=dave domain=daveslinux-]
[-real_local router skipped: prefix mismatch-]
[---------> system_aliases router <---------]
[-local_part=dave domain=daveslinux-]
[-checking domains-]
[-cached yes match for +local_domains-]
[-cached lookup data = NULL-]
[-daveslinux in "+local_domains"? yes (matched "+local_domains" - cached)-]
[-R: system_aliases for dave at daveslinux-]
[-calling system_aliases router-]
[-rda_interpret (string): ${lookup{$local_part}lsearch{/etc/aliases}}-]
[-search_open: lsearch "/etc/aliases"-]
[-search_find: file="/etc/aliases"-]
[- key="dave" partial=-1 affix=NULL starflags=0-]
[-LRU list:-]
[- 7/etc/aliases-]
[- End-]
[-internal_search_find: file="/etc/aliases"-]
[- type=lsearch key="dave"-]
[-file lookup required for dave-]
[- in /etc/aliases-]
[-lookup failed-]
[-expanded: -]
[-file is not a filter file-]
[-parse_forward_list: -]
[-system_aliases router declined for dave at daveslinux-]
[---------> userforward router <---------]
[-local_part=dave domain=daveslinux-]
[-checking domains-]
[-cached yes match for +local_domains-]
[-cached lookup data = NULL-]
[-daveslinux in "+local_domains"? yes (matched "+local_domains" - cached)-]
[-checking for local user-]
[-seeking password data for user "dave": cache not available-]
[-getpwnam() succeeded uid=1000 gid=1000-]
[-R: userforward for dave at daveslinux-]
[-checking require_files-]
[-seeking password data for user "dave": using cached result-]
[-getpwnam() succeeded uid=1000 gid=1000-]
[-check subsequent files for access by dave-]
[-file check: $home/.forward-]
[-expanded file: /home/dave/.forward-]
[-stat() yielded -1-]
[-errno = 2-]
[-userforward router skipped: file check-]
[---------> procmail router <---------]
[-local_part=dave domain=daveslinux-]
[-checking domains-]
[-cached yes match for +local_domains-]
[-cached lookup data = NULL-]
[-daveslinux in "+local_domains"? yes (matched "+local_domains" - cached)-]
[-checking for local user-]
[-seeking password data for user "dave": using cached result-]
[-getpwnam() succeeded uid=1000 gid=1000-]
[-R: procmail for dave at daveslinux-]
[-checking require_files-]
[-seeking password data for user "dave": using cached result-]
[-getpwnam() succeeded uid=1000 gid=1000-]
[-check subsequent files for access by dave-]
[-file check: ${if exists{/etc/procmailrc}{/etc/procmailrc}{${home}/.procmailrc}}-]
[-expanded file: /home/dave/.procmailrc-]
[-stat() yielded -1-]
[-errno = 2-]
[-procmail router skipped: file check-]
[---------> maildrop router <---------]
[-local_part=dave domain=daveslinux-]
[-checking domains-]
[-cached yes match for +local_domains-]
[-cached lookup data = NULL-]
[-daveslinux in "+local_domains"? yes (matched "+local_domains" - cached)-]
[-checking for local user-]
[-seeking password data for user "dave": using cached result-]
[-getpwnam() succeeded uid=1000 gid=1000-]
[-R: maildrop for dave at daveslinux-]
[-checking require_files-]
[-seeking password data for user "dave": using cached result-]
[-getpwnam() succeeded uid=1000 gid=1000-]
[-check subsequent files for access by dave-]
[-file check: ${home}/.mailfilter-]
[-expanded file: /home/dave/.mailfilter-]
[-stat() yielded -1-]
[-errno = 2-]
[-maildrop router skipped: file check-]
[---------> lowuid_aliases router <---------]
[-local_part=dave domain=daveslinux-]
[-checking domains-]
[-cached yes match for +local_domains-]
[-cached lookup data = NULL-]
[-daveslinux in "+local_domains"? yes (matched "+local_domains" - cached)-]
[-checking for local user-]
[-seeking password data for user "dave": using cached result-]
[-getpwnam() succeeded uid=1000 gid=1000-]
[-R: lowuid_aliases for dave at daveslinux (UID 1000)-]
[-checking "condition"-]
[- in ":@[]"? yes (matched "")-]
[-lowuid_aliases router skipped: condition failure-]
[---------> local_user router <---------]
[-local_part=dave domain=daveslinux-]
[-checking domains-]
[-cached yes match for +local_domains-]
[-cached lookup data = NULL-]
[-daveslinux in "+local_domains"? yes (matched "+local_domains" - cached)-]
[-checking local_parts-]
[-dave in "! root"? yes (end of list)-]
[-checking for local user-]
[-seeking password data for user "dave": using cached result-]
[-getpwnam() succeeded uid=1000 gid=1000-]
[-R: local_user for dave at daveslinux-]
[-calling local_user router-]
[-local_user router called for dave at daveslinux-]
[- domain = daveslinux-]
[-set transport mail_spool-]
[-queued for mail_spool transport: local_part = dave-]
[-domain = daveslinux-]
[- errors_to=NULL-]
[- domain_data=NULL localpart_data=NULL-]
[-routed by local_user router-]
[- envelope to: dave at daveslinux-]
[- transport: mail_spool-]
[->>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>-]
[-After routing:-]
[- Local deliveries:-]
[- dave at daveslinux-]
[- Remote deliveries:-]
[- Failed addresses:-]
[- Deferred addresses:-]
[-search_tidyup called-]
[->>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>-]
[---------> dave at daveslinux <---------]
[-locking /var/spool/exim4/db/retry.lockfile-]
[-locked /var/spool/exim4/db/retry.lockfile-]
[-EXIM_DBOPEN(/var/spool/exim4/db/retry)-]
[-returned from EXIM_DBOPEN-]
[-no retry data available-]
[-search_tidyup called-]
[-changed uid/gid: local delivery to dave <dave at daveslinux> transport=mail_spool-]
[- uid=1000 gid=8 pid=7723-]
[- auxiliary group list: <none>-]
[- home=/home/dave current=/home/dave-]
[-set_process_info: 7723 delivering 1WT45C-00020V-Lq to dave using mail_spool-]
[-T: appendfile for dave at daveslinux-]
[-appendfile transport entered-]
[-appendfile: mode=660 notify_comsat=0 quota=0 warning=0-]
[- file=/var/mail/dave format=unix-]
[- message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n-]
[- message_suffix=\n-]
[- maildir_use_size_file=no-]
[- locking by lockfile fcntl -]
[-lock name: /var/mail/dave.lock-]
[-hitch name: /var/mail/dave.lock.daveslinux.5333c94b.00001e2b-]
[-lock file created-]
[-mailbox /var/mail/dave is locked-]
[-writing to file /var/mail/dave-]
[-writing data block fd=6 size=44 timeout=0-]
[-writing data block fd=6 size=1381 timeout=0-]
[-writing data block fd=6 size=1 timeout=0-]
[-appendfile yields 0 with errno=0 more_errno=0-]
[-journalling dave at daveslinux-]
[-search_tidyup called-]
[-mail_spool transport returned OK for dave at daveslinux-]
[-post-process dave at daveslinux (0)-]
[-dave at daveslinux delivered-]
[-LOG: MAIN-]
[- => dave <dave at daveslinux> R=local_user T=mail_spool-]
[->>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>-]
[-changed uid/gid: post-delivery tidying-]
[- uid=107 gid=107 pid=7721-]
[- auxiliary group list: <none>-]
[-set_process_info: 7721 tidying up after delivering 1WT45C-00020V-Lq-]
[-Processing retry items-]
[-Succeeded addresses:-]
[-dave at daveslinux: no retry items-]
Failed addresses:
Deferred addresses:
end of retry processing
LOG: MAIN
Completed
end delivery of [-1WT45C-00020V-Lq-] {+1WTGtR-0001K9-L7+}
search_tidyup called
search_tidyup called
>>>>>>>>>>>>>>>> Exim [-pid=7721-] {+pid=5095+} terminating with rc=0 >>>>>>>>>>>>>>>>
More information about the Pkg-exim4-maintainers
mailing list