Re: [exim] Strange Sender Verification behaviour

Top Pagina
Delete this message
Reply to this message
Auteur: Renaud Allard
Datum:  
Aan: exim-users
CC: Philip Hazel
Onderwerp: Re: [exim] Strange Sender Verification behaviour
Well, the thing is, when doing it in debug mode, I receive this mail
back (normal, the address doesn't exists):

A message that you sent could not be delivered to one or more of its
recipients. This is a permanent error. The following address(es) failed:

  mail.llorien.org-1139481506-testing@???
    SMTP error from remote mail server after RCPT
TO:<mail.llorien.org-1139481506-testing@???>:
    host calligate2.cali.co.uk [62.172.47.141]: 550 5.1.1
<mail.llorien.org-1139481506-testing@???> is not a valid
mailbox


Which is the output I expected in the logs, namely failed after "RCPT
TO:" not after "MAIL FROM:".

I attached the full debug output.


Philip Hazel wrote:
> On Fri, 10 Feb 2006, Renaud Allard wrote:
>
>
>>After doing what you suggest, I get a mail delivery failure notification,
>>which logs well, the "RCPT TO:" failed.
>>
>>Here is the relevant part of the/tmp/xxx
>
>
> Not quite... :-)
>
>
>> SMTP>> RCPT TO:<mail.llorien.org-1139481506-testing@???>
>>waiting for data on socket
>>read response data: size=92
>> SMTP<< 550 5.1.1
>><mail.llorien.org-1139481506-testing@???> is not a valid
>>mailbox
>
>
> ... There should be a bit more in the debugging output that starts LOG,
> showing exactly what it wrote to the log file...
>



Exim version 4.60 uid=0 gid=0 pid=22965 D=fbb95cfd
Probably Berkeley DB version 1.8x (native mode)
Support for: crypteq IPv6 OpenSSL Content_Scanning Old_Demime
Lookups: lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmnz dnsdb mysql
Authenticators: cram_md5 plaintext spa
Routers: accept dnslookup ipliteral manualroute queryprogram redirect
Transports: appendfile/maildir autoreply pipe smtp
Fixed never_users: 0:3:1
changed uid/gid: forcing real = effective
uid=0 gid=0 pid=22965
auxiliary group list: <none>
finduser used cached passwd data for _exim
configuration file is /etc/exim/exim.conf
log selectors = 0000fffe 000d110d
cwd=/root 3 args: /usr/local/exim/exim -d mail.llorien.org-1139481506-testing@???
trusted user
admin user
changed uid/gid: privilege not needed
uid=105 gid=105 pid=22965
auxiliary group list: <none>
finduser used cached passwd data for _dspam
user name "Charlie Root" extracted from gecos field "Charlie Root"
originator: uid=0 gid=0 login=root name=Charlie Root
sender address = root@???
set_process_info: 22965 accepting a local non-SMTP message from <root@???>
spool directory space = 31485518K inodes = 4527443 check_space = 102400K inodes = 100 msg_size = 0
log directory space = 31485518K inodes = 4527443 check_space = 102400K inodes = 100
Sender: root@???
Recipients:
mail.llorien.org-1139481506-testing@???
search_tidyup called
>>Headers received:


address match: subject=root@??? pattern=*@+local_domains
llorien.org in "@ : gandalf.llorien.org : localhost : octools.com : octools.net : octools.org : llorien.org : llorien.net : llorien.com : dol-guldur.org : rhovanion.net : a-allard.be : actit.be : 2besides.net : overclockersonline.com : overclockersonline.org : overclockersonline.net : overclockersonline.be : seersight.be : seersight.net : erathia.be : gaye.be : morsiau.be"? yes (matched "llorien.org")
llorien.org in "+local_domains"? yes (matched "+local_domains")
root@??? 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 yielded: nikademus@???
LOG: address_rewrite MAIN
"root@???" from env-from rewritten as "nikademus@???" by rule 1
address match: subject=nikademus@??? pattern=*@+local_domains
llorien.org in "@ : gandalf.llorien.org : localhost : octools.com : octools.net : octools.org : llorien.org : llorien.net : llorien.com : dol-guldur.org : rhovanion.net : a-allard.be : actit.be : 2besides.net : overclockersonline.com : overclockersonline.org : overclockersonline.net : overclockersonline.be : seersight.be : seersight.net : erathia.be : gaye.be : morsiau.be"? yes (matched "llorien.org")
llorien.org in "+local_domains"? yes (matched "+local_domains")
nikademus@??? in "*@+local_domains"? yes (matched "*@+local_domains")
rewritten sender = nikademus@???
rewrite_one_header: type=F:
From: Charlie Root <root@???>
address match: subject=root@??? pattern=*@+local_domains
llorien.org in "@ : gandalf.llorien.org : localhost : octools.com : octools.net : octools.org : llorien.org : llorien.net : llorien.com : dol-guldur.org : rhovanion.net : a-allard.be : actit.be : 2besides.net : overclockersonline.com : overclockersonline.org : overclockersonline.net : overclockersonline.be : seersight.be : seersight.net : erathia.be : gaye.be : morsiau.be"? yes (matched "llorien.org")
llorien.org in "+local_domains"? yes (matched "+local_domains")
root@??? 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 yielded: nikademus@???
LOG: address_rewrite MAIN
"root@???" from from: rewritten as "nikademus@???" by rule 1
address match: subject=nikademus@??? pattern=*@+local_domains
llorien.org in "@ : gandalf.llorien.org : localhost : octools.com : octools.net : octools.org : llorien.org : llorien.net : llorien.com : dol-guldur.org : rhovanion.net : a-allard.be : actit.be : 2besides.net : overclockersonline.com : overclockersonline.org : overclockersonline.net : overclockersonline.be : seersight.be : seersight.net : erathia.be : gaye.be : morsiau.be"? yes (matched "llorien.org")
llorien.org in "+local_domains"? yes (matched "+local_domains")
nikademus@??? in "*@+local_domains"? yes (matched "*@+local_domains")
newlen=43 newtype=F newtext:
From: Charlie Root <nikademus@???>
remainder:
search_tidyup called
>>Headers after rewriting and local additions:

I Message-Id: <E1F7XfK-0005yP-Ei@???>
* From: Charlie Root <root@???>
F From: Charlie Root <nikademus@???>
* X-rewrote-sender: root@???
Date: Fri, 10 Feb 2006 13:46:28 +0100

Data file written for message 1F7XfK-0005yP-Ei
>>Generated Received: header line

P Received: from root (helo=root)
    by mail.llorien.org with local id=1F7XfK-0005yP-Ei
    for mail.llorien.org-1139481506-testing@???; Fri, 10 Feb 2006 13:46:28 +0100
calling local_scan(); timeout=300
local_scan() returned 0 NULL
Writing spool header file
Size of headers = 308
LOG: MAIN
  <= nikademus@??? U=root P=local S=317
search_tidyup called
search_tidyup called

>>>>>>>>>>>>>>>> Exim pid=22965 terminating with rc=0 >>>>>>>>>>>>>>>>

exec /usr/local/exim/exim -d=0xfbb95cfd -Mc 1F7XfK-0005yP-Ei
Exim version 4.60 uid=105 gid=105 pid=19062 D=fbb95cfd
Probably Berkeley DB version 1.8x (native mode)
Support for: crypteq IPv6 OpenSSL Content_Scanning Old_Demime
Lookups: lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmnz dnsdb mysql
Authenticators: cram_md5 plaintext spa
Routers: accept dnslookup ipliteral manualroute queryprogram redirect
Transports: appendfile/maildir autoreply pipe smtp
Fixed never_users: 0:3:1
changed uid/gid: forcing real = effective
uid=0 gid=105 pid=19062
auxiliary group list: <none>
finduser used cached passwd data for _exim
configuration file is /etc/exim/exim.conf
log selectors = 0000fffe 000d110d
cwd=/var/spool/exim 4 args: /usr/local/exim/exim -d=0xfbb95cfd -Mc 1F7XfK-0005yP-Ei
trusted user
admin user
skipping ACL configuration - not needed
finduser used cached passwd data for _dspam
set_process_info: 19062 delivering specified messages
set_process_info: 19062 delivering 1F7XfK-0005yP-Ei
reading spool file 1F7XfK-0005yP-Ei-H
user=root uid=0 gid=0 sender=nikademus@???
sender_local=1 ident=root
Non-recipients:
Empty Tree
---- End of tree ----
recipients_count=1
body_linecount=1 message_linecount=6
Delivery address list:
mail.llorien.org-1139481506-testing@???
locking /var/spool/exim/db/retry.lockfile
locked /var/spool/exim/db/retry.lockfile
EXIM_DBOPEN(/var/spool/exim/db/retry)
returned from EXIM_DBOPEN
opened hints database /var/spool/exim/db/retry: flags=0
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

Considering: mail.llorien.org-1139481506-testing@???
unique = mail.llorien.org-1139481506-testing@???
dbfn_read: key=R:smokedsalmon.uk.com
dbfn_read: key=R:mail.llorien.org-1139481506-testing@???
no domain retry record
no address retry record
mail.llorien.org-1139481506-testing@???: queued for routing
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

routing mail.llorien.org-1139481506-testing@???
--------> dspam_addspam router <--------
local_part=mail.llorien.org-1139481506-testing domain=smokedsalmon.uk.com
dspam_addspam router skipped: prefix mismatch
--------> dspam_falsepositive router <--------
local_part=mail.llorien.org-1139481506-testing domain=smokedsalmon.uk.com
dspam_falsepositive router skipped: prefix mismatch
--------> changepass_router router <--------
local_part=mail.llorien.org-1139481506-testing domain=smokedsalmon.uk.com
changepass_router router skipped: prefix mismatch
--------> newspipe_router router <--------
local_part=mail.llorien.org-1139481506-testing domain=smokedsalmon.uk.com
newspipe_router router skipped: prefix mismatch
--------> rfcipipe_router router <--------
local_part=mail.llorien.org-1139481506-testing domain=smokedsalmon.uk.com
rfcipipe_router router skipped: prefix mismatch
--------> mailman_router router <--------
local_part=mail.llorien.org-1139481506-testing domain=smokedsalmon.uk.com
checking require_files
file check: /usr/local/mailman/lists/$local_part/config.pck
expanded file: /usr/local/mailman/lists/mail.llorien.org-1139481506-testing/config.pck
stat() yielded -1
errno = 2
mailman_router router skipped: file check
--------> dnslookup_signed router <--------
local_part=mail.llorien.org-1139481506-testing domain=smokedsalmon.uk.com
checking domains
smokedsalmon.uk.com in "@ : gandalf.llorien.org : localhost : octools.com : octools.net : octools.org : llorien.org : llorien.net : llorien.com : dol-guldur.org : rhovanion.net : a-allard.be : actit.be : 2besides.net : overclockersonline.com : overclockersonline.org : overclockersonline.net : overclockersonline.be : seersight.be : seersight.net : erathia.be : gaye.be : morsiau.be"? no (end of list)
smokedsalmon.uk.com in "eriador.org"? no (end of list)
smokedsalmon.uk.com in "! +local_domains : !+relay_to_domains"? yes (end of list)
checking senders
address match: subject=nikademus@??? pattern=
llorien.org in ""? no (end of list)
address match: subject=nikademus@??? pattern=*
llorien.org in "*"? yes (matched "*")
nikademus@??? in "! : *"? yes (matched "*")
R: dnslookup_signed for mail.llorien.org-1139481506-testing@???
calling dnslookup_signed router
dnslookup_signed router called for mail.llorien.org-1139481506-testing@???
domain = smokedsalmon.uk.com
DNS lookup of smokedsalmon.uk.com (MX) succeeded
DNS lookup of calligate2.cali.co.uk (AAAA) gave NO_DATA
returning DNS_NODATA
DNS lookup of calligate2.cali.co.uk (A) succeeded
fully qualified name = smokedsalmon.uk.com
host_find_bydns yield = HOST_FOUND (2); returned hosts:
calligate2.cali.co.uk 62.172.47.141 MX=10
set transport remote_smtp_signed
queued for remote_smtp_signed transport: local_part = mail.llorien.org-1139481506-testing
domain = smokedsalmon.uk.com
errors_to=NULL
domain_data=NULL localpart_data=NULL
routed by dnslookup_signed router
envelope to: mail.llorien.org-1139481506-testing@???
transport: remote_smtp_signed
host calligate2.cali.co.uk [62.172.47.141] MX=10
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

After routing:
  Local deliveries:
  Remote deliveries:
    mail.llorien.org-1139481506-testing@???
  Failed addresses:
  Deferred addresses:
search_tidyup called

>>>>>>>>>>>>>>>> Remote deliveries >>>>>>>>>>>>>>>>

--------> mail.llorien.org-1139481506-testing@??? <--------
HMAC[md5](Jeuth4eejaeshui,nikademus=mail.llorien.org-1139481506-testing=smokedsalmon.uk.com)=0e7aad7e89f0887745053863a5346796
search_tidyup called
set_process_info: 19062 delivering 1F7XfK-0005yP-Ei: waiting for a remote delivery subprocess to finish
selecting on subprocess pipes
changed uid/gid: remote delivery to mail.llorien.org-1139481506-testing@??? with transport=remote_smtp_signed
uid=105 gid=105 pid=31449
auxiliary group list: <none>
set_process_info: 31449 delivering 1F7XfK-0005yP-Ei using remote_smtp_signed
T: remote_smtp_signed for mail.llorien.org-1139481506-testing@???
remote_smtp_signed transport entered
mail.llorien.org-1139481506-testing@???
smokedsalmon.uk.com in queue_smtp_domains? no (option unset)
checking status of calligate2.cali.co.uk
locking /var/spool/exim/db/retry.lockfile
locked /var/spool/exim/db/retry.lockfile
EXIM_DBOPEN(/var/spool/exim/db/retry)
returned from EXIM_DBOPEN
opened hints database /var/spool/exim/db/retry: flags=0
dbfn_read: key=T:calligate2.cali.co.uk:62.172.47.141
dbfn_read: key=T:calligate2.cali.co.uk:62.172.47.141:1F7XfK-0005yP-Ei
no host retry record
no message retry record
calligate2.cali.co.uk [62.172.47.141] status = usable
62.172.47.141 in serialize_hosts? no (option unset)
delivering 1F7XfK-0005yP-Ei to calligate2.cali.co.uk [62.172.47.141] (mail.llorien.org-1139481506-testing@???)
set_process_info: 31449 delivering 1F7XfK-0005yP-Ei to calligate2.cali.co.uk [62.172.47.141] (mail.llorien.org-1139481506-testing@???)
Connecting to calligate2.cali.co.uk [62.172.47.141]:25 ... connected
waiting for data on socket
read response data: size=75
SMTP<< 220 digest2.cali.co.uk ESMTP server ready (Alligate v1.5.11.16-279.6-2.2)
62.172.47.141 in hosts_avoid_esmtp? no (option unset)
SMTP>> EHLO mail.llorien.org

waiting for data on socket
read response data: size=24
  SMTP<< 250-digest2.cali.co.uk
waiting for data on socket
read response data: size=26
         250-AUTH LOGIN
         250 SIZE
62.172.47.141 in hosts_require_tls? no (option unset)
not using PIPELINING
62.172.47.141 in hosts_require_auth? no (option unset)
62.172.47.141 in hosts_try_auth? no (option unset)

SMTP>> MAIL FROM:<nikademus=mail.llorien.org-1139481506-testing=smokedsalmon.uk.com=hznzhxug@???> SIZE=1348

waiting for data on socket
read response data: size=8
SMTP<< 250 OK
SMTP>> RCPT TO:<mail.llorien.org-1139481506-testing@???>

waiting for data on socket
read response data: size=92
SMTP<< 550 5.1.1 <mail.llorien.org-1139481506-testing@???> is not a valid mailbox
ok=1 send_quit=1 send_rset=1 continue_more=0 yield=0 first_address is NULL
transport_check_waiting entered
sequence=1 local_max=500 global_max=-1
locking /var/spool/exim/db/wait-remote_smtp_signed.lockfile
locked /var/spool/exim/db/wait-remote_smtp_signed.lockfile
EXIM_DBOPEN(/var/spool/exim/db/wait-remote_smtp_signed)
returned from EXIM_DBOPEN
opened hints database /var/spool/exim/db/wait-remote_smtp_signed: flags=2
dbfn_read: key=calligate2.cali.co.uk
no messages waiting for calligate2.cali.co.uk
SMTP>> QUIT

set_process_info: 31449 delivering 1F7XfK-0005yP-Ei: just tried calligate2.cali.co.uk [62.172.47.141] for mail.llorien.org-1139481506-testing@???: result OK
Leaving remote_smtp_signed transport
set_process_info: 31449 delivering 1F7XfK-0005yP-Ei (just run remote_smtp_signed for mail.llorien.org-1139481506-testing@??? in subprocess)
search_tidyup called
reading pipe for subprocess 31449 (not ended)
read() yielded 5
selecting on subprocess pipes
reading pipe for subprocess 31449 (not ended)
read() yielded 258
selecting on subprocess pipes
reading pipe for subprocess 31449 (not ended)
read() yielded 2
Z0 item read
remote delivery process 31449 ended
set_process_info: 19062 delivering 1F7XfK-0005yP-Ei
post-process mail.llorien.org-1139481506-testing@??? (2)
LOG: MAIN
** mail.llorien.org-1139481506-testing@??? R=dnslookup_signed T=remote_smtp_signed: SMTP error from remote mail server after RCPT TO:<mail.llorien.org-1139481506-testing@???>: host calligate2.cali.co.uk [62.172.47.141]: 550 5.1.1 <mail.llorien.org-1139481506-testing@???> is not a valid mailbox
>>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>

changed uid/gid: post-delivery tidying
uid=105 gid=105 pid=19062
auxiliary group list: <none>
set_process_info: 19062 tidying up after delivering 1F7XfK-0005yP-Ei
Processing retry items
Succeeded addresses:
Failed addresses:
mail.llorien.org-1139481506-testing@???: no retry items
Deferred addresses:
end of retry processing
processing failed address mail.llorien.org-1139481506-testing@???
sending error message to: nikademus@???
writing data block fd=8 size=354 timeout=0
exec /usr/local/exim/exim -d=0xfbb95cfd -t -oem -oi -f <> -E1F7XfK-0005yP-Ei
Exim version 4.60 uid=105 gid=105 pid=22917 D=fbb95cfd
Probably Berkeley DB version 1.8x (native mode)
Support for: crypteq IPv6 OpenSSL Content_Scanning Old_Demime
Lookups: lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmnz dnsdb mysql
Authenticators: cram_md5 plaintext spa
Routers: accept dnslookup ipliteral manualroute queryprogram redirect
Transports: appendfile/maildir autoreply pipe smtp
Fixed never_users: 0:3:1
changed uid/gid: forcing real = effective
uid=0 gid=105 pid=22917
auxiliary group list: <none>
finduser used cached passwd data for _exim
configuration file is /etc/exim/exim.conf
log selectors = 0000fffe 000d110d
cwd=/var/spool/exim 8 args: /usr/local/exim/exim -d=0xfbb95cfd -t -oem -oi -f <> -E1F7XfK-0005yP-Ei
trusted user
admin user
changed uid/gid: privilege not needed
uid=105 gid=105 pid=22917
auxiliary group list: <none>
finduser used cached passwd data for _dspam
originator: uid=105 gid=105 login=_exim name=
sender address =
set_process_info: 22917 accepting a local non-SMTP message from <>
spool directory space = 31485496K inodes = 4527439 check_space = 102400K inodes = 100 msg_size = 0
log directory space = 31485496K inodes = 4527439 check_space = 102400K inodes = 100
Sender:
search_tidyup called
>>Headers received:

X-Failed-Recipients: mail.llorien.org-1139481506-testing@???
Auto-Submitted: auto-replied
From: Mail Delivery System <Mailer-Daemon@???>
To: nikademus@???
Subject: Mail delivery failed: returning message to sender

rewrite_one_header: type=F:
From: Mail Delivery System <Mailer-Daemon@???>
address match: subject=Mailer-Daemon@??? pattern=*@+local_domains
llorien.org in "@ : gandalf.llorien.org : localhost : octools.com : octools.net : octools.org : llorien.org : llorien.net : llorien.com : dol-guldur.org : rhovanion.net : a-allard.be : actit.be : 2besides.net : overclockersonline.com : overclockersonline.org : overclockersonline.net : overclockersonline.be : seersight.be : seersight.net : erathia.be : gaye.be : morsiau.be"? yes (matched "llorien.org")
llorien.org in "+local_domains"? yes (matched "+local_domains")
Mailer-Daemon@??? 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@??? pattern=*@+local_domains
llorien.org in "@ : gandalf.llorien.org : localhost : octools.com : octools.net : octools.org : llorien.org : llorien.net : llorien.com : dol-guldur.org : rhovanion.net : a-allard.be : actit.be : 2besides.net : overclockersonline.com : overclockersonline.org : overclockersonline.net : overclockersonline.be : seersight.be : seersight.net : erathia.be : gaye.be : morsiau.be"? yes (matched "llorien.org")
llorien.org in "+local_domains"? yes (matched "+local_domains")
Mailer-Daemon@??? in "*@+local_domains"? yes (matched "*@+local_domains")
rewrite_one_header: type=T:
To: nikademus@???
search_tidyup called
>>Headers after rewriting and local additions:

X-Failed-Recipients: mail.llorien.org-1139481506-testing@???
Auto-Submitted: auto-replied
F From: Mail Delivery System <Mailer-Daemon@???>
T To: nikademus@???
Subject: Mail delivery failed: returning message to sender
I Message-Id: <E1F7XfS-0005xd-8l@???>
Date: Fri, 10 Feb 2006 13:46:30 +0100

Data file written for message 1F7XfS-0005xd-8l
>>Generated Received: header line

P Received: from _exim (helo=_exim)
    by mail.llorien.org with local id=1F7XfS-0005xd-8l
    for nikademus@???; Fri, 10 Feb 2006 13:46:30 +0100
calling local_scan(); timeout=300
local_scan() returned 0 NULL
Writing spool header file
Size of headers = 479
LOG: MAIN
  <= <> R=1F7XfK-0005yP-Ei U=_exim P=local S=1427 T="Mail delivery failed: returning message to sender"
search_tidyup called
search_tidyup called

>>>>>>>>>>>>>>>> Exim pid=22917 terminating with rc=0 >>>>>>>>>>>>>>>>

exec /usr/local/exim/exim -d=0xfbb95cfd -Mc 1F7XfS-0005xd-8l
Writing spool header file
Size of headers = 308
LOG: MAIN
Completed
end delivery of 1F7XfK-0005yP-Ei
search_tidyup called
search_tidyup called
>>>>>>>>>>>>>>>> Exim pid=19062 terminating with rc=0 >>>>>>>>>>>>>>>>

Exim version 4.60 uid=105 gid=105 pid=8549 D=fbb95cfd
Probably Berkeley DB version 1.8x (native mode)
Support for: crypteq IPv6 OpenSSL Content_Scanning Old_Demime
Lookups: lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmnz dnsdb mysql
Authenticators: cram_md5 plaintext spa
Routers: accept dnslookup ipliteral manualroute queryprogram redirect
Transports: appendfile/maildir autoreply pipe smtp
Fixed never_users: 0:3:1
changed uid/gid: forcing real = effective
uid=0 gid=105 pid=8549
auxiliary group list: <none>
finduser used cached passwd data for _exim
configuration file is /etc/exim/exim.conf
log selectors = 0000fffe 000d110d
cwd=/var/spool/exim 4 args: /usr/local/exim/exim -d=0xfbb95cfd -Mc 1F7XfS-0005xd-8l
trusted user
admin user
skipping ACL configuration - not needed
finduser used cached passwd data for _dspam
set_process_info: 8549 delivering specified messages
set_process_info: 8549 delivering 1F7XfS-0005xd-8l
reading spool file 1F7XfS-0005xd-8l-H
user=_exim uid=105 gid=105 sender=
sender_local=0 ident=_exim
Non-recipients:
Empty Tree
---- End of tree ----
recipients_count=1
body_linecount=20 message_linecount=10
Delivery address list:
nikademus@???
locking /var/spool/exim/db/retry.lockfile
locked /var/spool/exim/db/retry.lockfile
EXIM_DBOPEN(/var/spool/exim/db/retry)
returned from EXIM_DBOPEN
opened hints database /var/spool/exim/db/retry: flags=0
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

Considering: nikademus@???
unique = nikademus@???
dbfn_read: key=R:llorien.org
dbfn_read: key=R:nikademus@???
no domain retry record
no address retry record
nikademus@???: queued for routing
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

routing nikademus@???
--------> dspam_addspam router <--------
local_part=nikademus domain=llorien.org
dspam_addspam router skipped: prefix mismatch
--------> dspam_falsepositive router <--------
local_part=nikademus domain=llorien.org
dspam_falsepositive router skipped: prefix mismatch
--------> changepass_router router <--------
local_part=nikademus domain=llorien.org
changepass_router router skipped: prefix mismatch
--------> newspipe_router router <--------
local_part=nikademus domain=llorien.org
newspipe_router router skipped: prefix mismatch
--------> rfcipipe_router router <--------
local_part=nikademus domain=llorien.org
rfcipipe_router router skipped: prefix mismatch
--------> mailman_router router <--------
local_part=nikademus domain=llorien.org
checking require_files
file check: /usr/local/mailman/lists/$local_part/config.pck
expanded file: /usr/local/mailman/lists/nikademus/config.pck
stat() yielded -1
errno = 2
mailman_router router skipped: file check
--------> dnslookup_signed router <--------
local_part=nikademus domain=llorien.org
checking domains
llorien.org in "@ : gandalf.llorien.org : localhost : octools.com : octools.net : octools.org : llorien.org : llorien.net : llorien.com : dol-guldur.org : rhovanion.net : a-allard.be : actit.be : 2besides.net : overclockersonline.com : overclockersonline.org : overclockersonline.net : overclockersonline.be : seersight.be : seersight.net : erathia.be : gaye.be : morsiau.be"? yes (matched "llorien.org")
llorien.org in "! +local_domains : !+relay_to_domains"? no (matched "! +local_domains")
dnslookup_signed router skipped: domains mismatch
--------> dnslookup_relay_to_domains router <--------
local_part=nikademus domain=llorien.org
checking domains
cached yes match for +local_domains
cached lookup data = NULL
llorien.org in "! +local_domains : +relay_to_domains"? no (matched "! +local_domains" - cached)
dnslookup_relay_to_domains router skipped: domains mismatch
--------> dnslookup router <--------
local_part=nikademus domain=llorien.org
checking domains
cached yes match for +local_domains
cached lookup data = NULL
llorien.org in "! +local_domains"? no (matched "! +local_domains" - cached)
dnslookup router skipped: domains mismatch
--------> hashed_local router <--------
local_part=nikademus domain=llorien.org
hashed_local router skipped: suffix mismatch
--------> virtual_aliases router <--------
local_part=nikademus domain=llorien.org
R: virtual_aliases for nikademus@???
calling virtual_aliases router
rda_interpret (string): ${lookup{$local_part@$domain}lsearch{/etc/virtaliases}}
search_open: lsearch "/etc/virtaliases"
search_find: file="/etc/virtaliases"
key="nikademus@???" partial=-1 affix=NULL starflags=0
LRU list:
:/etc/virtaliases
End
internal_search_find: file="/etc/virtaliases"
type=lsearch key="nikademus@???"
file lookup required for nikademus@???
in /etc/virtaliases
lookup yielded: n
expanded: n
file is not a filter file
parse_forward_list: n
extract item: n
virtual_aliases router generated n@???
errors_to=NULL transport=NULL
uid=unset gid=unset home=NULL
routed by virtual_aliases router
envelope to: nikademus@???
transport: <none>
locking /var/spool/exim/db/retry.lockfile
locked /var/spool/exim/db/retry.lockfile
EXIM_DBOPEN(/var/spool/exim/db/retry)
returned from EXIM_DBOPEN
opened hints database /var/spool/exim/db/retry: flags=0
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

Considering: n@???
unique = n@???
dbfn_read: key=R:llorien.org
dbfn_read: key=R:n@???
no domain retry record
no address retry record
n@???: queued for routing
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

routing n@???
--------> dspam_addspam router <--------
local_part=n domain=llorien.org
dspam_addspam router skipped: prefix mismatch
--------> dspam_falsepositive router <--------
local_part=n domain=llorien.org
dspam_falsepositive router skipped: prefix mismatch
--------> changepass_router router <--------
local_part=n domain=llorien.org
changepass_router router skipped: prefix mismatch
--------> newspipe_router router <--------
local_part=n domain=llorien.org
newspipe_router router skipped: prefix mismatch
--------> rfcipipe_router router <--------
local_part=n domain=llorien.org
rfcipipe_router router skipped: prefix mismatch
--------> mailman_router router <--------
local_part=n domain=llorien.org
checking require_files
file check: /usr/local/mailman/lists/$local_part/config.pck
expanded file: /usr/local/mailman/lists/n/config.pck
stat() yielded -1
errno = 2
mailman_router router skipped: file check
--------> dnslookup_signed router <--------
local_part=n domain=llorien.org
checking domains
llorien.org in "@ : gandalf.llorien.org : localhost : octools.com : octools.net : octools.org : llorien.org : llorien.net : llorien.com : dol-guldur.org : rhovanion.net : a-allard.be : actit.be : 2besides.net : overclockersonline.com : overclockersonline.org : overclockersonline.net : overclockersonline.be : seersight.be : seersight.net : erathia.be : gaye.be : morsiau.be"? yes (matched "llorien.org")
llorien.org in "! +local_domains : !+relay_to_domains"? no (matched "! +local_domains")
dnslookup_signed router skipped: domains mismatch
--------> dnslookup_relay_to_domains router <--------
local_part=n domain=llorien.org
checking domains
cached yes match for +local_domains
cached lookup data = NULL
llorien.org in "! +local_domains : +relay_to_domains"? no (matched "! +local_domains" - cached)
dnslookup_relay_to_domains router skipped: domains mismatch
--------> dnslookup router <--------
local_part=n domain=llorien.org
checking domains
cached yes match for +local_domains
cached lookup data = NULL
llorien.org in "! +local_domains"? no (matched "! +local_domains" - cached)
dnslookup router skipped: domains mismatch
--------> hashed_local router <--------
local_part=n domain=llorien.org
hashed_local router skipped: suffix mismatch
--------> virtual_aliases router <--------
local_part=n domain=llorien.org
R: virtual_aliases for n@???
calling virtual_aliases router
rda_interpret (string): ${lookup{$local_part@$domain}lsearch{/etc/virtaliases}}
search_open: lsearch "/etc/virtaliases"
cached open
search_find: file="/etc/virtaliases"
key="n@???" partial=-1 affix=NULL starflags=0
LRU list:
:/etc/virtaliases
End
internal_search_find: file="/etc/virtaliases"
type=lsearch key="n@???"
file lookup required for n@???
in /etc/virtaliases
lookup yielded: n
expanded: n
file is not a filter file
parse_forward_list: n
extract item: n
virtual_aliases router generated n@???
errors_to=NULL transport=NULL
uid=unset gid=unset home=NULL
routed by virtual_aliases router
envelope to: n@???
transport: <none>
locking /var/spool/exim/db/retry.lockfile
locked /var/spool/exim/db/retry.lockfile
EXIM_DBOPEN(/var/spool/exim/db/retry)
returned from EXIM_DBOPEN
opened hints database /var/spool/exim/db/retry: flags=0
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

Considering: n@???
unique = \0\n@???
dbfn_read: key=R:llorien.org
dbfn_read: key=R:n@???
no domain retry record
no address retry record
n@???: queued for routing
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

routing n@???
--------> dspam_addspam router <--------
local_part=n domain=llorien.org
dspam_addspam router skipped: prefix mismatch
--------> dspam_falsepositive router <--------
local_part=n domain=llorien.org
dspam_falsepositive router skipped: prefix mismatch
--------> changepass_router router <--------
local_part=n domain=llorien.org
changepass_router router skipped: prefix mismatch
--------> newspipe_router router <--------
local_part=n domain=llorien.org
newspipe_router router skipped: prefix mismatch
--------> rfcipipe_router router <--------
local_part=n domain=llorien.org
rfcipipe_router router skipped: prefix mismatch
--------> mailman_router router <--------
local_part=n domain=llorien.org
checking require_files
file check: /usr/local/mailman/lists/$local_part/config.pck
expanded file: /usr/local/mailman/lists/n/config.pck
stat() yielded -1
errno = 2
mailman_router router skipped: file check
--------> dnslookup_signed router <--------
local_part=n domain=llorien.org
checking domains
llorien.org in "@ : gandalf.llorien.org : localhost : octools.com : octools.net : octools.org : llorien.org : llorien.net : llorien.com : dol-guldur.org : rhovanion.net : a-allard.be : actit.be : 2besides.net : overclockersonline.com : overclockersonline.org : overclockersonline.net : overclockersonline.be : seersight.be : seersight.net : erathia.be : gaye.be : morsiau.be"? yes (matched "llorien.org")
llorien.org in "! +local_domains : !+relay_to_domains"? no (matched "! +local_domains")
dnslookup_signed router skipped: domains mismatch
--------> dnslookup_relay_to_domains router <--------
local_part=n domain=llorien.org
checking domains
cached yes match for +local_domains
cached lookup data = NULL
llorien.org in "! +local_domains : +relay_to_domains"? no (matched "! +local_domains" - cached)
dnslookup_relay_to_domains router skipped: domains mismatch
--------> dnslookup router <--------
local_part=n domain=llorien.org
checking domains
cached yes match for +local_domains
cached lookup data = NULL
llorien.org in "! +local_domains"? no (matched "! +local_domains" - cached)
dnslookup router skipped: domains mismatch
--------> hashed_local router <--------
local_part=n domain=llorien.org
hashed_local router skipped: suffix mismatch
--------> virtual_aliases router <--------
virtual_aliases router skipped: previously routed n@???
--------> system_aliases router <--------
local_part=n domain=llorien.org
R: system_aliases for n@???
calling system_aliases router
rda_interpret (string): ${lookup{$local_part}lsearch{/etc/aliases}}
search_open: lsearch "/etc/aliases"
search_find: file="/etc/aliases"
key="n" partial=-1 affix=NULL starflags=0
LRU list:
:/etc/aliases
:/etc/virtaliases
End
internal_search_find: file="/etc/aliases"
type=lsearch key="n"
file lookup required for n
in /etc/aliases
lookup failed
expanded:
file is not a filter file
parse_forward_list:
system_aliases router declined for n@???
--------> userforward router <--------
local_part=n domain=llorien.org
checking for local user
R: userforward for n@???
calling userforward router
rda_interpret (file): $home/.forward
expanded: /home/n/.forward
stat(/home/n/.)=0
/home/n/.forward does not exist
userforward router declined for n@???
--------> procmail router <--------
local_part=n domain=llorien.org
checking for local user
finduser used cached passwd data for n
R: procmail for n@???
checking require_files
finduser used cached passwd data for n
check subsequent files for access by n
file check: ${home}/.procmailrc
expanded file: /home/n/.procmailrc
stat() yielded -1
errno = 2
procmail router skipped: file check
--------> maildrop router <--------
local_part=n domain=llorien.org
checking for local user
finduser used cached passwd data for n
R: maildrop for n@???
checking require_files
finduser used cached passwd data for n
check subsequent files for access by n
file check: ${home}/.mailfilter
expanded file: /home/n/.mailfilter
stat() yielded -1
errno = 2
maildrop router skipped: file check
--------> local_user router <--------
local_part=n domain=llorien.org
checking domains
llorien.org in "llorien.org"? yes (matched "llorien.org")
checking for local user
finduser used cached passwd data for n
R: local_user for n@???
calling local_user router
local_user router called for n@???
domain = llorien.org
set transport maildir_home
queued for maildir_home transport: local_part = n
domain = llorien.org
errors_to=NULL
domain_data=NULL localpart_data=NULL
routed by local_user router
envelope to: n@???
transport: maildir_home
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

After routing:
  Local deliveries:
    n@???
  Remote deliveries:
  Failed addresses:
  Deferred addresses:
search_tidyup called

>>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>

--------> n@??? <--------
locking /var/spool/exim/db/retry.lockfile
locked /var/spool/exim/db/retry.lockfile
EXIM_DBOPEN(/var/spool/exim/db/retry)
returned from EXIM_DBOPEN
opened hints database /var/spool/exim/db/retry: flags=0
dbfn_read: key=T:n@???
no retry record exists
search_tidyup called
changed uid/gid: local delivery to n <n@???> transport=maildir_home
uid=1001 gid=10 pid=20891
auxiliary group list: <none>
home=/home/n current=/home/n
set_process_info: 20891 delivering 1F7XfS-0005xd-8l to n using maildir_home
direct command:
argv[0] = /usr/local/bin/dspam
argv[1] = -oMr
argv[2] = dspam-verified
argv[3] = --deliver=innocent
argv[4] = --client
argv[5] = --feature=ch,no,whitelist
argv[6] = --stdout
direct command after expansion:
argv[0] = /usr/local/bin/dspam
argv[1] = -oMr
argv[2] = dspam-verified
argv[3] = --deliver=innocent
argv[4] = --client
argv[5] = --feature=ch,no,whitelist
argv[6] = --stdout
T: maildir_home for n@???
appendfile transport entered
appendfile: mode=600 notify_comsat=0 quota=0 warning=0
directory=/home/n/Maildir format=maildir
message_prefix=null
message_suffix=null
maildir_use_size_file=no
ensuring maildir directories exist in /home/n/Maildir
delivering in maildir format in /home/n/Maildir
writing to file tmp/1139575590.H330545P20891.mail.llorien.org
process 7185 running as transport filter: write=9 read=10
process 20267 writing to transport filter
copying from the filter
writing data block fd=9 size=1525 timeout=0
waiting for filter process
waiting for writing process
writing data block fd=6 size=2238 timeout=0
end of filtering transport writing: yield=1
renaming temporary file
renamed tmp/1139575590.H330545P20891.mail.llorien.org as new/1139575590.H330545P20891.mail.llorien.org
appendfile yields 0 with errno=0 more_errno=0
journalling n@???/maildir_home
search_tidyup called
maildir_home transport returned OK for n@???
post-process n@??? (0)
n@??? delivered
n@???: children all complete
nikademus@???: children all complete
LOG: MAIN
=> n (n@???) <nikademus@???> R=local_user T=maildir_home S=2238 QT=3s DT=3s
>>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>

changed uid/gid: post-delivery tidying
uid=105 gid=105 pid=8549
auxiliary group list: <none>
set_process_info: 8549 tidying up after delivering 1F7XfS-0005xd-8l
Processing retry items
Succeeded addresses:
n@???: no retry items
n@???: no retry items
nikademus@???: no retry items
n@???: no retry items
nikademus@???: no retry items
nikademus@???: no retry items
Failed addresses:
Deferred addresses:
end of retry processing
LOG: MAIN
Completed
end delivery of 1F7XfS-0005xd-8l
search_tidyup called
search_tidyup called
>>>>>>>>>>>>>>>> Exim pid=8549 terminating with rc=0 >>>>>>>>>>>>>>>>