[Exim] callout help

Top Page
Delete this message
Reply to this message
Author: John Jetmore
Date:  
To: exim-users
Subject: [Exim] callout help
I'm trying to get recipient/callout verification working, and I can't
quite seem to get it to work. The line in the config is definitely
recognized, and it's even used for what appears to be normal routing
verification, but I never actually makes a callout that I see. The full
debug included at end of email, the highlights included here w/ comments.

the verification line (from the rcpt acl):

# more opts eventually, but keep it simple for testing
require verify = recipient/callout

The routers in question:

# This rewrites the outgoing address, if necessary. Since we lose the
# domain, which was our key, save the config info in $address_data so we
# can use it in other routers
cleanmail_rewrite:
driver = redirect
domains = dbmnz;CM_LD/db.cleanmail.db
hide_child_in_errmsg = true
redirect_router = cleanmail_outbound
address_data = CLEANMAIL!$domain_data
data = ${quote:$local_part}@${extract{1}{!}{$domain_data}}

# This router actually routes to the appropriate server.  It relies
# on cleanmail_rewrite setting $address_data to "CLEANMAIL!$domain_data"
cleanmail_outbound:
  driver = accept
  log_as_local = false
  condition = \
    ${if eq{CLEANMAIL}{${extract{1}{!}{$address_data}}}{Yes}{No}}
  transport = cleanmail_outbound


using these routers, it's possible to completely change the
envelope-recipient domain and also force it to a specific mail server. In
the test, I send mail to baduser@???. It gets delivered to
the server in question by DNS. the cleanmail_rewrite router changes it to
baduser@???. noroute.jetmore.net is a mail-only domain
which is not in DNS. I madeit up to prove my forcepathing was working.
cleanmail_outbound the forcepaths baduser@??? to
216.135.33.37. All of that works, proven empirically, and also shown
in the debug below. However, it doesn't do my callout verification.
here's selected parts of the debug:

25154 processing "require"
25154 check verify = recipient/callout
25154 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
25154 Verifying baduser@???
25154 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
25154 Considering baduser@???
25154 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
25154 routing baduser@???

<snip irrelevant routers>

ok, still in verification, cleanmail_rewrite correctly rewrites to
baduser@???

25154 --------> cleanmail_rewrite router <--------
25154 local_part=baduser domain=mx1.jetmore.net
25154 checking domains
25154 search_open: dbmnz "/local/cleanmail/lib/db.cleanmail.db"
25154 cached open
25154 search_find: file="/local/cleanmail/lib/db.cleanmail.db"
25154 key="mx1.jetmore.net" partial=-1 affix=NULL starflags=0
25154 LRU list:
25154 2/local/cleanmail/lib/db.cleanmail.db
25154 2/local/exim/etc/db.forcepaths.db
25154 2/local/exim/etc/local.domains.db
25154 2/wcs/var/mail-rules/db.denyhelo.db
25154 End
25154 internal_search_find: file="/local/cleanmail/lib/db.cleanmail.db"
25154 type=dbmnz key="mx1.jetmore.net"
25154 file lookup required for mx1.jetmore.net
25154 in /local/cleanmail/lib/db.cleanmail.db
25154 lookup yielded: noroute.jetmore.net!216.135.33.37
25154 mx1.jetmore.net in "dbmnz;/local/cleanmail/lib/db.cleanmail.db"? yes
(matched "dbmnz;/local/cleanmail/lib/db.cleanmail.db")
25154 processing address_data
25154 calling cleanmail_rewrite router
25154 rda_interpret (string):
${quote:$local_part}@${extract{1}{!}{$domain_data}}
25154 expanded: baduser@???
25154 file is not a filter file
25154 parse_forward_list: baduser@???
25154 extract item: baduser@???
25154 cleanmail_rewrite router generated baduser@???
25154 errors_to=NULL transport=NULL
25154 uid=unset gid=unset home=NULL
25154 routed by cleanmail_rewrite router
25154 envelope to: baduser@???
25154 transport: <none>

email address got translated,now verify baduser@???.
correctly follows redirect_router and starts at cleanmail_outbound

25154 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
25154 Considering baduser@???
25154 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
25154 routing baduser@???
25154 --------> cleanmail_outbound router <--------
25154 local_part=baduser domain=noroute.jetmore.net
25154 checking "condition"
25154 calling cleanmail_outbound router
25154 cleanmail_outbound router called for baduser@???
25154 domain = noroute.jetmore.net
25154 set transport cleanmail_outbound
25154 queued for cleanmail_outbound transport: local_part = baduser
25154 domain = noroute.jetmore.net
25154 errors_to=NULL
25154 domain_data=NULL localpart_data=NULL
25154 routed by cleanmail_outbound router
25154 envelope to: baduser@???
25154 transport: cleanmail_outbound
25154 ----------- end verify ------------
25154 require: condition test succeeded
25154 processing "accept"
25154 accept: condition test succeeded
25154 accept: condition test succeeded

OK, great, the verification succeeded, but I never saw the callout take
place. As hinted by the local_part baduser, this isn't a valid
address and should be rejected up front. What have I missed? I see the
redirection during verification section in spec.txt, but it looks like
since I only generated one address it should work. Any help greatly
appreciated.

--John

<all debug from smtp session>

25152 Connection request from 209.83.132.66 port 3650
25152 1 SMTP accept process running
25152 Listening...
25154 host in rfc1413_hosts? yes (matched "*")
25154 doing ident callback
25154 ident connection to 209.83.132.66 failed: Connection refused
25154 sender_fullhost = [209.83.132.66]
25154 sender_rcvhost = [209.83.132.66]
25154 Process 25154 is handling incoming connection from [209.83.132.66]
25154 checking for IP options
25154 no IP options found
25154 host in host_lookup? yes (matched "*")
25154 looking up host name for 209.83.132.66
25154 IP address lookup yielded bb1.betterbox.net
25154 gethostbyname looked up these IP addresses:
25154   name=bb1.betterbox.net address=209.83.132.66
25154 checking addresses for bb1.betterbox.net
25154   209.83.132.66
25154 sender_fullhost = bb1.betterbox.net [209.83.132.66]
25154 sender_rcvhost = bb1.betterbox.net ([209.83.132.66])
25154 set_process_info: 25154 handling incoming connection from
bb1.betterbox.net [209.83.132.66]
25154 host in host_reject_connection? no (option unset)
25154 host in sender_unqualified_hosts? no (option unset)
25154 host in recipient_unqualified_hosts? no (option unset)
25154 host in helo_verify_hosts? no (option unset)
25154 host in helo_try_verify_hosts? no (option unset)
25154 host in helo_accept_junk_hosts? yes (matched "*")
25154 LOG: smtp_connection MAIN
25154   SMTP connection from bb1.betterbox.net [209.83.132.66] (TCP/IP
connection count = 1)
25154 SMTP>> 220 bunsen.cinergycom.net ESMTP mailer ready at Thu, 24 Apr
2003 09:09:54 -0500
25154 Process 25154 is ready for new message
25154 smtp_setup_msg entered
25154 SMTP<< EHLO bb1.betterbox.net
25154 sender_fullhost = bb1.betterbox.net [209.83.132.66]
25154 sender_rcvhost = bb1.betterbox.net ([209.83.132.66])
25154 set_process_info: 25154 handling incoming connection from
bb1.betterbox.net [209.83.132.66]
25154 host in pipelining_advertise_hosts? yes (matched "*")
25154 SMTP>> 250-bunsen.cinergycom.net Hello bb1.betterbox.net
[209.83.132.66]
25154 250-SIZE 31457280
25154 250-PIPELINING
25154 250 HELP
25154 SMTP<< MAIL FROM:<eloise@???>
25154 using ACL "check_sender"
25154 processing "deny"
25154 check condition = ${if eq{$sender_helo_name}{}{yes}{no}}
25154                 = no
25154 deny: condition test failed
25154 processing "accept"
25154 accept: condition test succeeded
25154 SMTP>> 250 OK
25154 SMTP<< RCPT TO:<baduser@???>
25154 using ACL "acl_choose_check_rcpt"
25154 processing "accept"
25154 check acl = ${if
eq{$domain}{${lookup{$domain}dbmnz{/local/cleanmail/lib/db.cleanmail.db}{$domain}{}}}{acl_cleanmail_main}{check_recipient}}
25154 search_open: dbmnz "/local/cleanmail/lib/db.cleanmail.db"
25154 search_find: file="/local/cleanmail/lib/db.cleanmail.db"
25154   key="mx1.jetmore.net" partial=-1 affix=NULL starflags=0
25154 LRU list:
25154   2/local/cleanmail/lib/db.cleanmail.db
25154   End
25154 internal_search_find: file="/local/cleanmail/lib/db.cleanmail.db"
25154   type=dbmnz key="mx1.jetmore.net"
25154 file lookup required for mx1.jetmore.net
25154   in /local/cleanmail/lib/db.cleanmail.db
25154 lookup yielded: noroute.jetmore.net!216.135.33.37
25154           = acl_cleanmail_main
25154 using ACL "acl_cleanmail_main"
25154 processing "accept"
25154 check hosts = :
25154 host in ":"? no (end of list)
25154 accept: condition test failed
25154 processing "deny"
25154 check local_parts = ^.*[@%!/|] : ^\\.
25154 baduser in "^.*[@%!/|] : ^\."? no (end of list)
25154 deny: condition test failed
25154 processing "accept"
25154 check local_parts = postmaster:abuse
25154 baduser in "postmaster:abuse"? no (end of list)
25154 accept: condition test failed
25154 processing "deny"
25154 check condition =
${lookup{${lc:$sender_helo_name}}dbmnz{/wcs/var/mail-rules/db.denyhelo.db}{Yes}{No}}
25154 search_open: dbmnz "/wcs/var/mail-rules/db.denyhelo.db"
25154 search_find: file="/wcs/var/mail-rules/db.denyhelo.db"
25154   key="bb1.betterbox.net" partial=-1 affix=NULL starflags=0
25154 LRU list:
25154   2/wcs/var/mail-rules/db.denyhelo.db
25154   2/local/cleanmail/lib/db.cleanmail.db
25154   End
25154 internal_search_find: file="/wcs/var/mail-rules/db.denyhelo.db"
25154   type=dbmnz key="bb1.betterbox.net"
25154 file lookup required for bb1.betterbox.net
25154   in /wcs/var/mail-rules/db.denyhelo.db
25154 lookup failed
25154                 = No
25154 deny: condition test failed
25154 processing "require"
25154 check verify = sender
25154 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
25154 Verifying eloise@???
25154 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
25154 Considering eloise@???
25154 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
25154 routing eloise@???
25154 --------> forcepaths router <--------
25154 local_part=eloise domain=bb1.betterbox.net
25154 checking domains
25154 search_open: dbmnz "/local/exim/etc/local.domains.db"
25154 search_find: file="/local/exim/etc/local.domains.db"
25154   key="bb1.betterbox.net" partial=-1 affix=NULL starflags=0
25154 LRU list:
25154   2/local/exim/etc/local.domains.db
25154   2/wcs/var/mail-rules/db.denyhelo.db
25154   2/local/cleanmail/lib/db.cleanmail.db
25154   End
25154 internal_search_find: file="/local/exim/etc/local.domains.db"
25154   type=dbmnz key="bb1.betterbox.net"
25154 file lookup required for bb1.betterbox.net
25154   in /local/exim/etc/local.domains.db
25154 lookup failed
25154 bb1.betterbox.net in "bunsen : bunsen.cinergycom.net :
dbmnz;/local/exim/etc/local.domains.db"? no (end of list)
25154 bb1.betterbox.net in "! +local_domains"? yes (end of list)
25154 cached lookup data = NULL
25154 calling forcepaths router
25154 forcepaths router called for eloise@???
25154   domain = bb1.betterbox.net
25154 search_open: dbmnz "/local/exim/etc/db.forcepaths.db"
25154 search_find: file="/local/exim/etc/db.forcepaths.db"
25154   key="bb1.betterbox.net" partial=-1 affix=NULL starflags=0
25154 LRU list:
25154   2/local/exim/etc/db.forcepaths.db
25154   2/local/exim/etc/local.domains.db
25154   2/wcs/var/mail-rules/db.denyhelo.db
25154   2/local/cleanmail/lib/db.cleanmail.db
25154   End
25154 internal_search_find: file="/local/exim/etc/db.forcepaths.db"
25154   type=dbmnz key="bb1.betterbox.net"
25154 file lookup required for bb1.betterbox.net
25154   in /local/exim/etc/db.forcepaths.db
25154 lookup failed
25154 forcepaths router declined for eloise@???
25154 --------> stalemail router <--------
25154 local_part=eloise domain=bb1.betterbox.net
25154 checking domains
25154 cached no match for +local_domains
25154 bb1.betterbox.net in "! +local_domains"? yes (end of list)
25154 cached lookup data = NULL
25154 checking "condition"
25154 stalemail router skipped: condition failure
25154 --------> cleanmail_inbound router <--------
25154 local_part=eloise domain=bb1.betterbox.net
25154 cleanmail_inbound router skipped: verify 1 0 0
25154 --------> cleanmail_rewrite router <--------
25154 local_part=eloise domain=bb1.betterbox.net
25154 checking domains
25154 search_open: dbmnz "/local/cleanmail/lib/db.cleanmail.db"
25154   cached open
25154 search_find: file="/local/cleanmail/lib/db.cleanmail.db"
25154   key="bb1.betterbox.net" partial=-1 affix=NULL starflags=0
25154 LRU list:
25154   2/local/cleanmail/lib/db.cleanmail.db
25154   2/local/exim/etc/db.forcepaths.db
25154   2/local/exim/etc/local.domains.db
25154   2/wcs/var/mail-rules/db.denyhelo.db
25154   End
25154 internal_search_find: file="/local/cleanmail/lib/db.cleanmail.db"
25154   type=dbmnz key="bb1.betterbox.net"
25154 file lookup required for bb1.betterbox.net
25154   in /local/cleanmail/lib/db.cleanmail.db
25154 lookup failed
25154 bb1.betterbox.net in "dbmnz;/local/cleanmail/lib/db.cleanmail.db"?
no (end of list)
25154 cleanmail_rewrite router skipped: domains mismatch
25154 --------> cleanmail_outbound router <--------
25154 local_part=eloise domain=bb1.betterbox.net
25154 checking "condition"
25154 cleanmail_outbound router skipped: condition failure
25154 --------> funnel_junk_to_statler router <--------
25154 local_part=eloise domain=bb1.betterbox.net
25154 funnel_junk_to_statler router skipped: verify 1 0 0
25154 --------> funnel_to_statler router <--------
25154 local_part=eloise domain=bb1.betterbox.net
25154 funnel_to_statler router skipped: verify 1 0 0
25154 --------> lookuphost router <--------
25154 local_part=eloise domain=bb1.betterbox.net
25154 checking domains
25154 cached no match for +local_domains
25154 bb1.betterbox.net in "! +local_domains"? yes (end of list)
25154 cached lookup data = NULL
25154 calling lookuphost router
25154 lookuphost router called for eloise@???
25154   domain = bb1.betterbox.net
25154 DNS lookup of bb1.betterbox.net (MX) gave NO_DATA
25154 returning DNS_NODATA
25154 DNS lookup of bb1.betterbox.net (A) succeeded
25154 209.83.132.66 in "0.0.0.0 : 127.0.0.0/8 : 10.0.0.0/8 :
192.168.0.0/16 : 172.16.0.0/16"? no (end of list)
25154 fully qualified name = bb1.betterbox.net
25154 bb1.betterbox.net 209.83.132.66 mx=-1 sort=-447
25154 set transport remote_smtp
25154 queued for remote_smtp transport: local_part = eloise
25154 domain = bb1.betterbox.net
25154   errors_to=NULL
25154   domain_data=NULL localpart_data=NULL
25154 routed by lookuphost router
25154   envelope to: eloise@???
25154   transport: remote_smtp
25154   host bb1.betterbox.net [209.83.132.66]
25154 ----------- end verify ------------
25154 sender eloise@??? verified ok
25154 require: condition test succeeded
25154 processing "require"
25154 check verify = recipient/callout
25154 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
25154 Verifying baduser@???
25154 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
25154 Considering baduser@???
25154 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
25154 routing baduser@???
25154 --------> forcepaths router <--------
25154 local_part=baduser domain=mx1.jetmore.net
25154 checking domains
25154 search_open: dbmnz "/local/exim/etc/local.domains.db"
25154   cached open
25154 search_find: file="/local/exim/etc/local.domains.db"
25154   key="mx1.jetmore.net" partial=-1 affix=NULL starflags=0
25154 LRU list:
25154   2/local/exim/etc/local.domains.db
25154   2/local/cleanmail/lib/db.cleanmail.db
25154   2/local/exim/etc/db.forcepaths.db
25154   2/wcs/var/mail-rules/db.denyhelo.db
25154   End
25154 internal_search_find: file="/local/exim/etc/local.domains.db"
25154   type=dbmnz key="mx1.jetmore.net"
25154 file lookup required for mx1.jetmore.net
25154   in /local/exim/etc/local.domains.db
25154 lookup failed
25154 mx1.jetmore.net in "bunsen : bunsen.cinergycom.net :
dbmnz;/local/exim/etc/local.domains.db"? no (end of list)
25154 mx1.jetmore.net in "! +local_domains"? yes (end of list)
25154 cached lookup data = NULL
25154 calling forcepaths router
25154 forcepaths router called for baduser@???
25154   domain = mx1.jetmore.net
25154 search_open: dbmnz "/local/exim/etc/db.forcepaths.db"
25154   cached open
25154 search_find: file="/local/exim/etc/db.forcepaths.db"
25154   key="mx1.jetmore.net" partial=-1 affix=NULL starflags=0
25154 LRU list:
25154   2/local/exim/etc/db.forcepaths.db
25154   2/local/exim/etc/local.domains.db
25154   2/local/cleanmail/lib/db.cleanmail.db
25154   2/wcs/var/mail-rules/db.denyhelo.db
25154   End
25154 internal_search_find: file="/local/exim/etc/db.forcepaths.db"
25154   type=dbmnz key="mx1.jetmore.net"
25154 file lookup required for mx1.jetmore.net
25154   in /local/exim/etc/db.forcepaths.db
25154 lookup failed
25154 forcepaths router declined for baduser@???
25154 --------> stalemail router <--------
25154 local_part=baduser domain=mx1.jetmore.net
25154 checking domains
25154 cached no match for +local_domains
25154 mx1.jetmore.net in "! +local_domains"? yes (end of list)
25154 cached lookup data = NULL
25154 checking "condition"
25154 stalemail router skipped: condition failure
25154 --------> cleanmail_inbound router <--------
25154 local_part=baduser domain=mx1.jetmore.net
25154 cleanmail_inbound router skipped: verify 2 0 0
25154 --------> cleanmail_rewrite router <--------
25154 local_part=baduser domain=mx1.jetmore.net
25154 checking domains
25154 search_open: dbmnz "/local/cleanmail/lib/db.cleanmail.db"
25154   cached open
25154 search_find: file="/local/cleanmail/lib/db.cleanmail.db"
25154   key="mx1.jetmore.net" partial=-1 affix=NULL starflags=0
25154 LRU list:
25154   2/local/cleanmail/lib/db.cleanmail.db
25154   2/local/exim/etc/db.forcepaths.db
25154   2/local/exim/etc/local.domains.db
25154   2/wcs/var/mail-rules/db.denyhelo.db
25154   End
25154 internal_search_find: file="/local/cleanmail/lib/db.cleanmail.db"
25154   type=dbmnz key="mx1.jetmore.net"
25154 file lookup required for mx1.jetmore.net
25154   in /local/cleanmail/lib/db.cleanmail.db
25154 lookup yielded: noroute.jetmore.net!216.135.33.37
25154 mx1.jetmore.net in "dbmnz;/local/cleanmail/lib/db.cleanmail.db"? yes
(matched "dbmnz;/local/cleanmail/lib/db.cleanmail.db")
25154 processing address_data
25154 calling cleanmail_rewrite router
25154 rda_interpret (string):
${quote:$local_part}@${extract{1}{!}{$domain_data}}
25154 expanded: baduser@???
25154 file is not a filter file
25154 parse_forward_list: baduser@???
25154 extract item: baduser@???
25154 cleanmail_rewrite router generated baduser@???
25154   errors_to=NULL transport=NULL
25154   uid=unset gid=unset home=NULL
25154 routed by cleanmail_rewrite router
25154   envelope to: baduser@???
25154   transport: <none>
25154 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
25154 Considering baduser@???
25154 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
25154 routing baduser@???
25154 --------> cleanmail_outbound router <--------
25154 local_part=baduser domain=noroute.jetmore.net
25154 checking "condition"
25154 calling cleanmail_outbound router
25154 cleanmail_outbound router called for baduser@???
25154   domain = noroute.jetmore.net
25154 set transport cleanmail_outbound
25154 queued for cleanmail_outbound transport: local_part = baduser
25154 domain = noroute.jetmore.net
25154   errors_to=NULL
25154   domain_data=NULL localpart_data=NULL
25154 routed by cleanmail_outbound router
25154   envelope to: baduser@???
25154   transport: cleanmail_outbound
25154 ----------- end verify ------------
25154 require: condition test succeeded
25154 processing "accept"
25154 accept: condition test succeeded
25154 accept: condition test succeeded
25154 SMTP>> 250 Accepted
25154 SMTP<< DATA
25154 SMTP>> 354 Enter message, ending with "." on a line by itself
25154 search_tidyup called
25154 host in ignore_fromline_hosts? no (option unset)
25154 >>Headers received:
25154 To: baduser@???
25154 From: eloise@???
25154 Subject: test
25154
25154 rewrite_one_header: type=T:
25154   To: baduser@???
25154 rewrite_one_header: type=F:
25154   From: eloise@???
25154 search_tidyup called
25154 >>Headers after local additions:
25154 P Received: from bb1.betterbox.net ([209.83.132.66])
25154   by bunsen.cinergycom.net with esmtp (Exim 4.14)
25154   id 198hQA-0006Xi-Ep
25154   for baduser@???; Thu, 24 Apr 2003 09:09:54 -0500
25154 T To: baduser@???
25154 F From: eloise@???
25154   Subject: test
25154 I Message-Id: <E198hQA-0006Xi-Ep@???>
25154   Date: Thu, 24 Apr 2003 09:09:54 -0500
25154
25154 Data file written for message 198hQA-0006Xi-Ep
25154 using ACL "check_message"
25154 processing "accept"
25154 accept: condition test succeeded
25154 calling local_scan(); timeout=300
25154 local_scan() returned 0 NULL
25154 Writing spool header file
25154 Size of headers = 348
25154 LOG: MAIN
25154   <= eloise@??? H=bb1.betterbox.net [209.83.132.66]
P=esmtp S=372
25154 SMTP>> 250 OK id=198hQA-0006Xi-Ep
25154 search_tidyup called
25154 Sender: eloise@???
25154 Recipients:
25154   baduser@???
25154 forked delivery process 25155
25154 Process 25154 is ready for new message
25154 smtp_setup_msg entered
25155 set_process_info: 25155 delivering 198hQA-0006Xi-Ep
25155 reading spool file 198hQA-0006Xi-Ep-H
25155 user=root uid=0 gid=0 sender=eloise@???
25155 sender_fullhost = bb1.betterbox.net [209.83.132.66]
25155 sender_rcvhost = bb1.betterbox.net ([209.83.132.66])
25155 sender_local=0 ident=unset
25155 Non-recipients:
25155 Empty Tree
25155 ---- End of tree ----
25155 recipients_count=1
25155 body_linecount=1 message_linecount=9
25155 Delivery address list:
25155   baduser@???
25155 locking /local/exim/spool/db/retry.lockfile
25155 locked /local/exim/spool/db/retry.lockfile
25155 opened hints database /local/exim/spool/db/retry: flags=0
25155 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
25155 Considering: baduser@???
25155 unique = baduser@???
25155 dbfn_read: key=R:mx1.jetmore.net
25155 dbfn_read: key=R:baduser@???
25155 no domain retry record
25155 no address retry record
25155 baduser@???: queued for routing
25155 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
25155 routing baduser@???
25155 --------> forcepaths router <--------
25155 local_part=baduser domain=mx1.jetmore.net
25155 checking domains
25155 search_open: dbmnz "/local/exim/etc/local.domains.db"
25155 search_find: file="/local/exim/etc/local.domains.db"
25155   key="mx1.jetmore.net" partial=-1 affix=NULL starflags=0
25155 LRU list:
25155   2/local/exim/etc/local.domains.db
25155   End
25155 internal_search_find: file="/local/exim/etc/local.domains.db"
25155   type=dbmnz key="mx1.jetmore.net"
25155 file lookup required for mx1.jetmore.net
25155   in /local/exim/etc/local.domains.db
25155 lookup failed
25155 mx1.jetmore.net in "bunsen : bunsen.cinergycom.net :
dbmnz;/local/exim/etc/local.domains.db"? no (end of list)
25155 mx1.jetmore.net in "! +local_domains"? yes (end of list)
25155 cached lookup data = NULL
25155 calling forcepaths router
25155 forcepaths router called for baduser@???
25155   domain = mx1.jetmore.net
25155 search_open: dbmnz "/local/exim/etc/db.forcepaths.db"
25155 search_find: file="/local/exim/etc/db.forcepaths.db"
25155   key="mx1.jetmore.net" partial=-1 affix=NULL starflags=0
25155 LRU list:
25155   2/local/exim/etc/db.forcepaths.db
25155   2/local/exim/etc/local.domains.db
25155   End
25155 internal_search_find: file="/local/exim/etc/db.forcepaths.db"
25155   type=dbmnz key="mx1.jetmore.net"
25155 file lookup required for mx1.jetmore.net
25155   in /local/exim/etc/db.forcepaths.db
25155 lookup failed
25155 forcepaths router declined for baduser@???
25155 --------> stalemail router <--------
25155 local_part=baduser domain=mx1.jetmore.net
25155 checking domains
25155 cached no match for +local_domains
25155 mx1.jetmore.net in "! +local_domains"? yes (end of list)
25155 cached lookup data = NULL
25155 checking "condition"
25155 stalemail router skipped: condition failure
25155 --------> cleanmail_inbound router <--------
25155 local_part=baduser domain=mx1.jetmore.net
25155 checking domains
25155 search_open: dbmnz "/local/cleanmail/lib/db.cleanmail.db"
25155 search_find: file="/local/cleanmail/lib/db.cleanmail.db"
25155   key="mx1.jetmore.net" partial=-1 affix=NULL starflags=0
25155 LRU list:
25155   2/local/cleanmail/lib/db.cleanmail.db
25155   2/local/exim/etc/db.forcepaths.db
25155   2/local/exim/etc/local.domains.db
25155   End
25155 internal_search_find: file="/local/cleanmail/lib/db.cleanmail.db"
25155   type=dbmnz key="mx1.jetmore.net"
25155 file lookup required for mx1.jetmore.net
25155   in /local/cleanmail/lib/db.cleanmail.db
25155 lookup yielded: noroute.jetmore.net!216.135.33.37
25155 mx1.jetmore.net in "dbmnz;/local/cleanmail/lib/db.cleanmail.db"? yes
(matched "dbmnz;/local/cleanmail/lib/db.cleanmail.db")
25155 checking "condition"
25155 calling cleanmail_inbound router
25155 cleanmail_inbound router called for baduser@???
25155   domain = mx1.jetmore.net
25155 set transport cleanmail_inbound
25155 queued for cleanmail_inbound transport: local_part = baduser
25155 domain = mx1.jetmore.net
25155   errors_to=NULL
25155   domain_data=noroute.jetmore.net!216.135.33.37 localpart_data=NULL
25155 routed by cleanmail_inbound router
25155   envelope to: baduser@???
25155   transport: cleanmail_inbound
25155 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
25155 After routing:
25155   Local deliveries:
25155     baduser@???
25155   Remote deliveries:
25155   Failed addresses:
25155   Deferred addresses:
25155 search_tidyup called
25155 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
25155 --------> baduser@??? <--------
25155 locking /local/exim/spool/db/retry.lockfile
25155 locked /local/exim/spool/db/retry.lockfile
25155 opened hints database /local/exim/spool/db/retry: flags=0
25155 dbfn_read: key=T:baduser@???
25155 no retry record exists
25155 search_tidyup called
25156 changed uid/gid: local delivery to baduser <baduser@???>
transport=cleanmail_inbound
25156   uid=0 gid=0 pid=25156
25156   auxiliary group list: <none>
25156   home=NULL current=/
25156 set_process_info: 25156 delivering 198hQA-0006Xi-Ep to baduser using
cleanmail_inbound
25156 cleanmail_inbound transport entered
25156 direct command:
25156   argv[0] = /local/cleanmail/bin/clean
25156 direct command after expansion:
25156   argv[0] = /local/cleanmail/bin/clean
25158 set_process_info: 25158 reading output from
|/local/cleanmail/bin/clean
25156 Writing message to pipe
25156 writing data block fd=9 size=37 timeout=3600
25156 writing data block fd=9 size=34 timeout=3600
25156 writing data block fd=9 size=5 timeout=3600
25156 writing data block fd=9 size=372 timeout=3600
25156 writing data block fd=9 size=2 timeout=3600
25154 SMTP<< QUIT
25154 SMTP>> 221 bunsen.cinergycom.net closing connection
25154 LOG: smtp_connection MAIN
25154   SMTP connection from bb1.betterbox.net [209.83.132.66] closed by
QUIT
25154 search_tidyup called
25152 child 25154 ended: status=0x0
25152 0 SMTP accept processes now running
25152 Listening...
25156 cleanmail_inbound transport yielded 0
25156 search_tidyup called
25155 journalling baduser@???
25155 cleanmail_inbound transport returned OK for baduser@???
25155 post-process baduser@??? (0)
25155 baduser@??? delivered
25155 LOG: MAIN
25155   => baduser@??? R=cleanmail_inbound T=cleanmail_inbound
25155 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
25155 changed uid/gid: post-delivery tidying
25155   uid=0 gid=0 pid=25155
25155   auxiliary group list: <none>
25155 set_process_info: 25155 tidying up after delivering 198hQA-0006Xi-Ep
25155 Processing retry items
25155 Succeeded addresses:
25155 baduser@???: no retry items
25155 Failed addresses:
25155 Deferred addresses:
25155 end of retry processing
25155 LOG: MAIN
25155   Completed
25155 end delivery of 198hQA-0006Xi-Ep