DKIM: clarify debug output

[exim.git] / test / stderr / 0022

diff --git a/test/stderr/0022 b/test/stderr/0022
index dbda13f9c5a80860356bc986e294d3a4841940c8..d0ab9c00edb85cb85fcc3eefa5f441afcfd80592 100644 (file)
--- a/test/stderr/0022
+++ b/test/stderr/0022
@@ -5,6 +5,8 @@ configuration file is TESTSUITE/test-config
 admin user
 changed uid/gid: privilege not needed
   uid=EXIM_UID gid=EXIM_GID pid=pppp
 admin user
 changed uid/gid: privilege not needed
   uid=EXIM_UID gid=EXIM_GID pid=pppp

+seeking password data for user "CALLER": cache not available
+getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID

 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 sender address = CALLER@test.ex
 sender_fullhost = [V4NET.9.8.7]
 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 sender address = CALLER@test.ex
 sender_fullhost = [V4NET.9.8.7]


@@ -20,45 +22,56 @@ host in recipient_unqualified_hosts? no (option unset)
 host in helo_verify_hosts? no (option unset)
 host in helo_try_verify_hosts? no (option unset)
 host in helo_accept_junk_hosts? no (option unset)
 host in helo_verify_hosts? no (option unset)
 host in helo_try_verify_hosts? no (option unset)
 host in helo_accept_junk_hosts? no (option unset)

-SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000\r
+SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000

 smtp_setup_msg entered
 smtp_setup_msg entered

+SMTP<< helo test
+test in helo_lookup_domains? no (end of list)
+sender_fullhost = (test) [V4NET.9.8.7]
+sender_rcvhost = [V4NET.9.8.7] (helo=test)
+set_process_info: pppp handling incoming connection from (test) [V4NET.9.8.7]
+SMTP>> 250 myhost.test.ex Hello test [V4NET.9.8.7]

 SMTP<< mail from:<x@y>
 SMTP<< mail from:<x@y>

-SMTP>> 250 OK\r
+spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
+log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
+SMTP>> 250 OK

 SMTP<< rcpt to:<warn_empty@test.ex>
 using ACL "warn_empty"
 SMTP<< rcpt to:<warn_empty@test.ex>
 using ACL "warn_empty"

-processing "warn"
-warn: condition test succeeded
-processing "accept"
-accept: condition test succeeded
-SMTP>> 250 Accepted\r
+processing "warn" (TESTSUITE/test-config 29)
+warn: condition test succeeded in ACL "warn_empty"
+processing "accept" (TESTSUITE/test-config 30)
+accept: condition test succeeded in ACL "warn_empty"
+end of ACL "warn_empty": ACCEPT
+SMTP>> 250 Accepted

 SMTP<< data
 SMTP<< data

-SMTP>> 354 Enter message, ending with "." on a line by itself\r
+SMTP>> 354 Enter message, ending with "." on a line by itself

 search_tidyup called
 search_tidyup called

-host in ignore_fromline_hosts? no (option unset)

 >>Headers received:
 
 >>Headers received:
 

+qualify & rewrite recipients list
+global rewrite rules
+rewrite headers

 search_tidyup called
 >>Headers after rewriting and local additions:
 
 search_tidyup called
 >>Headers after rewriting and local additions:
 

+Data file name: TESTSUITE/spool//input//10HmbF-0005vi-00-D

 Data file written for message 10HmbF-0005vi-00
 >>Generated Received: header line
 Data file written for message 10HmbF-0005vi-00
 >>Generated Received: header line

-P Received: from [V4NET.9.8.7]
+P Received: from [V4NET.9.8.7] (helo=test)

        by myhost.test.ex with smtp (Exim x.yz)
        (envelope-from <x@y>)
        id 10HmbF-0005vi-00
        by myhost.test.ex with smtp (Exim x.yz)
        (envelope-from <x@y>)
        id 10HmbF-0005vi-00

-       for warn_empty@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
-calling local_scan(); timeout=300
-local_scan() returned 0 NULL
+       for warn_empty@test.ex;
+       Tue, 2 Mar 1999 09:44:33 +0000

 LOG: MAIN
 LOG: MAIN

-  <= x@y H=[V4NET.9.8.7] P=smtp S=sss
-SMTP>> 250 OK id=10HmbF-0005vi-00\r
+  <= x@y H=(test) [V4NET.9.8.7] P=smtp S=sss
+SMTP>> 250 OK id=10HmbF-0005vi-00

 smtp_setup_msg entered
 SMTP<< quit
 smtp_setup_msg entered
 SMTP<< quit

-SMTP>> 221 myhost.test.ex closing connection\r
+SMTP>> 221 myhost.test.ex closing connection

 LOG: smtp_connection MAIN
 LOG: smtp_connection MAIN

-  SMTP connection from [V4NET.9.8.7] closed by QUIT
+  SMTP connection from (test) [V4NET.9.8.7] closed by QUIT

 search_tidyup called
 search_tidyup called

->>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
+>>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>

 Exim version x.yz ....
 changed uid/gid: forcing real = effective
   uid=uuuu gid=CALLER_GID pid=pppp
 Exim version x.yz ....
 changed uid/gid: forcing real = effective
   uid=uuuu gid=CALLER_GID pid=pppp


@@ -66,6 +79,8 @@ configuration file is TESTSUITE/test-config
 admin user
 changed uid/gid: privilege not needed
   uid=EXIM_UID gid=EXIM_GID pid=pppp
 admin user
 changed uid/gid: privilege not needed
   uid=EXIM_UID gid=EXIM_GID pid=pppp

+seeking password data for user "CALLER": cache not available
+getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID

 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 sender address = CALLER@test.ex
 sender_fullhost = [V4NET.9.8.7]
 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 sender address = CALLER@test.ex
 sender_fullhost = [V4NET.9.8.7]


@@ -81,47 +96,59 @@ host in recipient_unqualified_hosts? no (option unset)
 host in helo_verify_hosts? no (option unset)
 host in helo_try_verify_hosts? no (option unset)
 host in helo_accept_junk_hosts? no (option unset)
 host in helo_verify_hosts? no (option unset)
 host in helo_try_verify_hosts? no (option unset)
 host in helo_accept_junk_hosts? no (option unset)

-SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000\r
+SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000

 smtp_setup_msg entered
 smtp_setup_msg entered

+SMTP<< helo test
+test in helo_lookup_domains? no (end of list)
+sender_fullhost = (test) [V4NET.9.8.7]
+sender_rcvhost = [V4NET.9.8.7] (helo=test)
+set_process_info: pppp handling incoming connection from (test) [V4NET.9.8.7]
+SMTP>> 250 myhost.test.ex Hello test [V4NET.9.8.7]

 SMTP<< mail from:<x@y>
 SMTP<< mail from:<x@y>

-SMTP>> 250 OK\r
+spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
+log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
+SMTP>> 250 OK

 SMTP<< rcpt to:<warn_log@test.ex>
 using ACL "warn_log"
 SMTP<< rcpt to:<warn_log@test.ex>
 using ACL "warn_log"

-processing "warn"
-warn: condition test succeeded
+processing "warn" (TESTSUITE/test-config 33)
+l_message: warn log message
+warn: condition test succeeded in ACL "warn_log"

 LOG: MAIN
 LOG: MAIN

-  H=[V4NET.9.8.7] Warning: warn log message
-processing "accept"
-accept: condition test succeeded
-SMTP>> 250 Accepted\r
+  H=(test) [V4NET.9.8.7] Warning: warn log message
+processing "accept" (TESTSUITE/test-config 34)
+accept: condition test succeeded in ACL "warn_log"
+end of ACL "warn_log": ACCEPT
+SMTP>> 250 Accepted

 SMTP<< data
 SMTP<< data

-SMTP>> 354 Enter message, ending with "." on a line by itself\r
+SMTP>> 354 Enter message, ending with "." on a line by itself

 search_tidyup called
 search_tidyup called

-host in ignore_fromline_hosts? no (option unset)

 >>Headers received:
 
 >>Headers received:
 

+qualify & rewrite recipients list
+global rewrite rules
+rewrite headers

 search_tidyup called
 >>Headers after rewriting and local additions:
 
 search_tidyup called
 >>Headers after rewriting and local additions:
 

+Data file name: TESTSUITE/spool//input//10HmbG-0005vi-00-D

 Data file written for message 10HmbG-0005vi-00
 >>Generated Received: header line
 Data file written for message 10HmbG-0005vi-00
 >>Generated Received: header line

-P Received: from [V4NET.9.8.7]
+P Received: from [V4NET.9.8.7] (helo=test)

        by myhost.test.ex with smtp (Exim x.yz)
        (envelope-from <x@y>)
        id 10HmbG-0005vi-00
        by myhost.test.ex with smtp (Exim x.yz)
        (envelope-from <x@y>)
        id 10HmbG-0005vi-00

-       for warn_log@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
-calling local_scan(); timeout=300
-local_scan() returned 0 NULL
+       for warn_log@test.ex;
+       Tue, 2 Mar 1999 09:44:33 +0000

 LOG: MAIN
 LOG: MAIN

-  <= x@y H=[V4NET.9.8.7] P=smtp S=sss
-SMTP>> 250 OK id=10HmbG-0005vi-00\r
+  <= x@y H=(test) [V4NET.9.8.7] P=smtp S=sss
+SMTP>> 250 OK id=10HmbG-0005vi-00

 smtp_setup_msg entered
 SMTP<< quit
 smtp_setup_msg entered
 SMTP<< quit

-SMTP>> 221 myhost.test.ex closing connection\r
+SMTP>> 221 myhost.test.ex closing connection

 LOG: smtp_connection MAIN
 LOG: smtp_connection MAIN

-  SMTP connection from [V4NET.9.8.7] closed by QUIT
+  SMTP connection from (test) [V4NET.9.8.7] closed by QUIT

 search_tidyup called
 search_tidyup called

->>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
+>>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>

 Exim version x.yz ....
 changed uid/gid: forcing real = effective
   uid=uuuu gid=CALLER_GID pid=pppp
 Exim version x.yz ....
 changed uid/gid: forcing real = effective
   uid=uuuu gid=CALLER_GID pid=pppp


@@ -129,6 +156,8 @@ configuration file is TESTSUITE/test-config
 admin user
 changed uid/gid: privilege not needed
   uid=EXIM_UID gid=EXIM_GID pid=pppp
 admin user
 changed uid/gid: privilege not needed
   uid=EXIM_UID gid=EXIM_GID pid=pppp

+seeking password data for user "CALLER": cache not available
+getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID

 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 sender address = CALLER@test.ex
 sender_fullhost = [V4NET.9.8.7]
 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 sender address = CALLER@test.ex
 sender_fullhost = [V4NET.9.8.7]


@@ -144,48 +173,60 @@ host in recipient_unqualified_hosts? no (option unset)
 host in helo_verify_hosts? no (option unset)
 host in helo_try_verify_hosts? no (option unset)
 host in helo_accept_junk_hosts? no (option unset)
 host in helo_verify_hosts? no (option unset)
 host in helo_try_verify_hosts? no (option unset)
 host in helo_accept_junk_hosts? no (option unset)

-SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000\r
+SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000

 smtp_setup_msg entered
 smtp_setup_msg entered

+SMTP<< helo test
+test in helo_lookup_domains? no (end of list)
+sender_fullhost = (test) [V4NET.9.8.7]
+sender_rcvhost = [V4NET.9.8.7] (helo=test)
+set_process_info: pppp handling incoming connection from (test) [V4NET.9.8.7]
+SMTP>> 250 myhost.test.ex Hello test [V4NET.9.8.7]

 SMTP<< mail from:<x@y>
 SMTP<< mail from:<x@y>

-SMTP>> 250 OK\r
+spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
+log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
+SMTP>> 250 OK

 SMTP<< rcpt to:<warn_user@test.ex>
 using ACL "warn_user"
 SMTP<< rcpt to:<warn_user@test.ex>
 using ACL "warn_user"

-processing "warn"
-warn: condition test succeeded
-processing "accept"
-accept: condition test succeeded
-SMTP>> 250 Accepted\r
+processing "warn" (TESTSUITE/test-config 37)
+  message: warn user message
+warn: condition test succeeded in ACL "warn_user"
+processing "accept" (TESTSUITE/test-config 38)
+accept: condition test succeeded in ACL "warn_user"
+end of ACL "warn_user": ACCEPT
+SMTP>> 250 Accepted

 SMTP<< data
 SMTP<< data

-SMTP>> 354 Enter message, ending with "." on a line by itself\r
+SMTP>> 354 Enter message, ending with "." on a line by itself

 search_tidyup called
 search_tidyup called

-host in ignore_fromline_hosts? no (option unset)

 >>Headers received:
 
 >>Headers received:
 

+qualify & rewrite recipients list
+global rewrite rules
+rewrite headers

 search_tidyup called
 >>Headers after rewriting and local additions:
 
 search_tidyup called
 >>Headers after rewriting and local additions:
 

+Data file name: TESTSUITE/spool//input//10HmbH-0005vi-00-D

 Data file written for message 10HmbH-0005vi-00
 >>Generated Received: header line
 Data file written for message 10HmbH-0005vi-00
 >>Generated Received: header line

-P Received: from [V4NET.9.8.7]
+P Received: from [V4NET.9.8.7] (helo=test)

        by myhost.test.ex with smtp (Exim x.yz)
        (envelope-from <x@y>)
        id 10HmbH-0005vi-00
        by myhost.test.ex with smtp (Exim x.yz)
        (envelope-from <x@y>)
        id 10HmbH-0005vi-00

-       for warn_user@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
+       for warn_user@test.ex;
+       Tue, 2 Mar 1999 09:44:33 +0000

 >>Headers added by MAIL or RCPT ACL:
   X-ACL-Warn: warn user message
 >>
 >>Headers added by MAIL or RCPT ACL:
   X-ACL-Warn: warn user message
 >>

-calling local_scan(); timeout=300
-local_scan() returned 0 NULL

 LOG: MAIN
 LOG: MAIN

-  <= x@y H=[V4NET.9.8.7] P=smtp S=sss
-SMTP>> 250 OK id=10HmbH-0005vi-00\r
+  <= x@y H=(test) [V4NET.9.8.7] P=smtp S=sss
+SMTP>> 250 OK id=10HmbH-0005vi-00

 smtp_setup_msg entered
 SMTP<< quit
 smtp_setup_msg entered
 SMTP<< quit

-SMTP>> 221 myhost.test.ex closing connection\r
+SMTP>> 221 myhost.test.ex closing connection

 LOG: smtp_connection MAIN
 LOG: smtp_connection MAIN

-  SMTP connection from [V4NET.9.8.7] closed by QUIT
+  SMTP connection from (test) [V4NET.9.8.7] closed by QUIT

 search_tidyup called
 search_tidyup called

->>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
+>>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>

 >>> host in hosts_connection_nolog? no (end of list)
 LOG: SMTP connection from [V4NET.9.8.7]
 >>> host in host_lookup? no (option unset)
 >>> host in hosts_connection_nolog? no (end of list)
 LOG: SMTP connection from [V4NET.9.8.7]
 >>> host in host_lookup? no (option unset)


@@ -195,18 +236,24 @@ LOG: SMTP connection from [V4NET.9.8.7]
 >>> host in helo_verify_hosts? no (option unset)
 >>> host in helo_try_verify_hosts? no (option unset)
 >>> host in helo_accept_junk_hosts? no (option unset)
 >>> host in helo_verify_hosts? no (option unset)
 >>> host in helo_try_verify_hosts? no (option unset)
 >>> host in helo_accept_junk_hosts? no (option unset)

+>>> test in helo_lookup_domains? no (end of list)

 >>> using ACL "defer"
 >>> using ACL "defer"

->>> processing "defer"
->>> defer: condition test succeeded
-LOG: H=[V4NET.9.8.7] F=<x@y> temporarily rejected RCPT <defer@y>: forcibly deferred
+>>> processing "defer" (TESTSUITE/test-config 51)
+>>>   message: forcibly deferred
+>>> defer: condition test succeeded in ACL "defer"
+>>> end of ACL "defer": DEFER
+LOG: H=(test) [V4NET.9.8.7] F=<x@y> temporarily rejected RCPT <defer@y>: forcibly deferred

 >>> using ACL "accept"
 >>> using ACL "accept"

->>> processing "accept"
->>> accept: condition test succeeded
+>>> processing "accept" (TESTSUITE/test-config 24)
+>>> accept: condition test succeeded in ACL "accept"
+>>> end of ACL "accept": ACCEPT

 >>> using ACL "drop"
 >>> using ACL "drop"

->>> processing "drop"
->>> drop: condition test succeeded
-LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <drop@y>: forcibly dropped
-LOG: SMTP connection from [V4NET.9.8.7] closed by DROP in ACL
+>>> processing "drop" (TESTSUITE/test-config 41)
+>>>   message: forcibly dropped
+>>> drop: condition test succeeded in ACL "drop"
+>>> end of ACL "drop": DROP
+LOG: H=(test) [V4NET.9.8.7] F=<x@y> rejected RCPT <drop@y>: forcibly dropped
+LOG: SMTP connection from (test) [V4NET.9.8.7] closed by DROP in ACL

 >>> host in hosts_connection_nolog? no (end of list)
 LOG: SMTP connection from [V4NET.9.8.7]
 >>> host in host_lookup? no (option unset)
 >>> host in hosts_connection_nolog? no (end of list)
 LOG: SMTP connection from [V4NET.9.8.7]
 >>> host in host_lookup? no (option unset)


@@ -216,13 +263,15 @@ LOG: SMTP connection from [V4NET.9.8.7]
 >>> host in helo_verify_hosts? no (option unset)
 >>> host in helo_try_verify_hosts? no (option unset)
 >>> host in helo_accept_junk_hosts? no (option unset)
 >>> host in helo_verify_hosts? no (option unset)
 >>> host in helo_try_verify_hosts? no (option unset)
 >>> host in helo_accept_junk_hosts? no (option unset)

+>>> test in helo_lookup_domains? no (end of list)

 >>> using ACL "defer_senders"
 >>> using ACL "defer_senders"

->>> processing "defer"
+>>> processing "defer" (TESTSUITE/test-config 54)

 >>> check senders = :
 >>>  in ":"? yes (matched "")
 >>> check senders = :
 >>>  in ":"? yes (matched "")

->>> defer: condition test succeeded
-LOG: H=[V4NET.9.8.7] F=<> temporarily rejected RCPT <defer_senders@y>
-LOG: SMTP connection from [V4NET.9.8.7] closed by QUIT
+>>> defer: condition test succeeded in ACL "defer_senders"
+>>> end of ACL "defer_senders": DEFER
+LOG: H=(test) [V4NET.9.8.7] F=<> temporarily rejected RCPT <defer_senders@y>
+LOG: SMTP connection from (test) [V4NET.9.8.7] closed by QUIT

 >>> host in hosts_connection_nolog? no (end of list)
 LOG: SMTP connection from [V4NET.9.8.7]
 >>> host in host_lookup? no (option unset)
 >>> host in hosts_connection_nolog? no (end of list)
 LOG: SMTP connection from [V4NET.9.8.7]
 >>> host in host_lookup? no (option unset)


@@ -232,21 +281,24 @@ LOG: SMTP connection from [V4NET.9.8.7]
 >>> host in helo_verify_hosts? no (option unset)
 >>> host in helo_try_verify_hosts? no (option unset)
 >>> host in helo_accept_junk_hosts? no (option unset)
 >>> host in helo_verify_hosts? no (option unset)
 >>> host in helo_try_verify_hosts? no (option unset)
 >>> host in helo_accept_junk_hosts? no (option unset)

+>>> test in helo_lookup_domains? no (end of list)

 >>> using ACL "delay_accept"
 >>> using ACL "delay_accept"

->>> processing "accept"
+>>> processing "accept" (TESTSUITE/test-config 57)

 >>> check delay = 1s
 >>> delay modifier requests 1-second delay
 >>> delay skipped in -bh checking mode
 >>> check delay = 1s
 >>> delay modifier requests 1-second delay
 >>> delay skipped in -bh checking mode

->>> accept: condition test succeeded
+>>> accept: condition test succeeded in ACL "delay_accept"
+>>> end of ACL "delay_accept": ACCEPT

 >>> using ACL "delay_warn"
 >>> using ACL "delay_warn"

->>> processing "warn"
+>>> processing "warn" (TESTSUITE/test-config 60)

 >>> check delay = 1s
 >>> delay modifier requests 1-second delay
 >>> delay skipped in -bh checking mode
 >>> check delay = 1s
 >>> delay modifier requests 1-second delay
 >>> delay skipped in -bh checking mode

->>> warn: condition test succeeded
->>> processing "accept"
->>> accept: condition test succeeded
-LOG: SMTP connection from [V4NET.9.8.7] closed by QUIT
+>>> warn: condition test succeeded in ACL "delay_warn"
+>>> processing "accept" (TESTSUITE/test-config 61)
+>>> accept: condition test succeeded in ACL "delay_warn"
+>>> end of ACL "delay_warn": ACCEPT
+LOG: SMTP connection from (test) [V4NET.9.8.7] closed by QUIT

 >>> host in hosts_connection_nolog? no (end of list)
 LOG: SMTP connection from [V4NET.9.8.7]
 >>> host in host_lookup? no (option unset)
 >>> host in hosts_connection_nolog? no (end of list)
 LOG: SMTP connection from [V4NET.9.8.7]
 >>> host in host_lookup? no (option unset)


@@ -256,34 +308,45 @@ LOG: SMTP connection from [V4NET.9.8.7]
 >>> host in helo_verify_hosts? no (option unset)
 >>> host in helo_try_verify_hosts? no (option unset)
 >>> host in helo_accept_junk_hosts? no (option unset)
 >>> host in helo_verify_hosts? no (option unset)
 >>> host in helo_try_verify_hosts? no (option unset)
 >>> host in helo_accept_junk_hosts? no (option unset)

+>>> test in helo_lookup_domains? no (end of list)

 >>> using ACL "host_check"
 >>> using ACL "host_check"

->>> processing "deny"
+>>> processing "deny" (TESTSUITE/test-config 71)

 >>> check hosts = net-lsearch;TESTSUITE/aux-var/0022.hosts
 >>> host in "net-lsearch;TESTSUITE/aux-var/0022.hosts"? yes (matched "net-lsearch;TESTSUITE/aux-var/0022.hosts")
 >>> check hosts = net-lsearch;TESTSUITE/aux-var/0022.hosts
 >>> host in "net-lsearch;TESTSUITE/aux-var/0022.hosts"? yes (matched "net-lsearch;TESTSUITE/aux-var/0022.hosts")

->>> deny: condition test succeeded
-LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <host_check@y>: host data >A host-specific message<
+>>>   message: host data >$host_data<
+>>> deny: condition test succeeded in ACL "host_check"
+>>> end of ACL "host_check": DENY
+LOG: H=(test) [V4NET.9.8.7] F=<x@y> rejected RCPT <host_check@y>: host data >A host-specific message<

 >>> using ACL "host_check"
 >>> using ACL "host_check"

->>> processing "deny"
+>>> processing "deny" (TESTSUITE/test-config 71)

 >>> check hosts = net-lsearch;TESTSUITE/aux-var/0022.hosts
 >>> host in "net-lsearch;TESTSUITE/aux-var/0022.hosts"? yes (matched "net-lsearch;TESTSUITE/aux-var/0022.hosts")
 >>> check hosts = net-lsearch;TESTSUITE/aux-var/0022.hosts
 >>> host in "net-lsearch;TESTSUITE/aux-var/0022.hosts"? yes (matched "net-lsearch;TESTSUITE/aux-var/0022.hosts")

->>> deny: condition test succeeded
-LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <host_check@y>: host data >A host-specific message<
+>>>   message: host data >$host_data<
+>>> deny: condition test succeeded in ACL "host_check"
+>>> end of ACL "host_check": DENY
+LOG: H=(test) [V4NET.9.8.7] F=<x@y> rejected RCPT <host_check@y>: host data >A host-specific message<

 >>> using ACL "host_check2"
 >>> using ACL "host_check2"

->>> processing "deny"
+>>> processing "deny" (TESTSUITE/test-config 75)
+>>>   message: host data >$host_data<

 >>> check hosts = +some_hosts
 >>> host in "net-lsearch;TESTSUITE/aux-var/0022.hosts"? yes (matched "net-lsearch;TESTSUITE/aux-var/0022.hosts")
 >>> host in "+some_hosts"? yes (matched "+some_hosts")
 >>> check hosts = +some_hosts
 >>> host in "net-lsearch;TESTSUITE/aux-var/0022.hosts"? yes (matched "net-lsearch;TESTSUITE/aux-var/0022.hosts")
 >>> host in "+some_hosts"? yes (matched "+some_hosts")

->>> deny: condition test succeeded
-LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <host_check2@y>: host data >A host-specific message<
+>>> deny: condition test succeeded in ACL "host_check2"
+>>> end of ACL "host_check2": DENY
+LOG: H=(test) [V4NET.9.8.7] F=<x@y> rejected RCPT <host_check2@y>: host data >A host-specific message<

 >>> using ACL "host_check2"
 >>> using ACL "host_check2"

->>> processing "deny"
+>>> processing "deny" (TESTSUITE/test-config 75)
+>>>   message: host data >$host_data<

 >>> check hosts = +some_hosts
 >>> host in "+some_hosts"? yes (matched "+some_hosts" - cached)
 >>> check hosts = +some_hosts
 >>> host in "+some_hosts"? yes (matched "+some_hosts" - cached)

->>> deny: condition test succeeded
-LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <host_check2@y>: host data >A host-specific message<
-LOG: SMTP connection from [V4NET.9.8.7] closed by QUIT
+>>> deny: condition test succeeded in ACL "host_check2"
+>>> end of ACL "host_check2": DENY
+LOG: H=(test) [V4NET.9.8.7] F=<x@y> rejected RCPT <host_check2@y>: host data >A host-specific message<
+LOG: SMTP connection from (test) [V4NET.9.8.7] closed by QUIT

 LOG: smtp_connection MAIN
   SMTP connection from CALLER
 LOG: smtp_connection MAIN
   SMTP connection from CALLER

+LOG: MAIN
+  Connections=0

 LOG: MAIN
   <= CALLER@test.ex U=CALLER P=local-smtp S=sss
 LOG: MAIN
 LOG: MAIN
   <= CALLER@test.ex U=CALLER P=local-smtp S=sss
 LOG: MAIN


@@ -317,16 +380,19 @@ LOG: SMTP connection from [V4NET.9.8.7]
 >>> host in helo_verify_hosts? no (option unset)
 >>> host in helo_try_verify_hosts? no (option unset)
 >>> host in helo_accept_junk_hosts? no (option unset)
 >>> host in helo_verify_hosts? no (option unset)
 >>> host in helo_try_verify_hosts? no (option unset)
 >>> host in helo_accept_junk_hosts? no (option unset)

+>>> test in helo_lookup_domains? no (end of list)

 >>> using ACL "nested_drop"
 >>> using ACL "nested_drop"

->>> processing "accept"
+>>> processing "accept" (TESTSUITE/test-config 44)

 >>> check acl = drop
 >>> check acl = drop

->>> using ACL "drop"
->>> processing "drop"
->>> drop: condition test succeeded
->>> accept: condition test yielded "drop"
+>>>  using ACL "drop"
+>>>  processing "drop" (TESTSUITE/test-config 41)
+>>>    message: forcibly dropped
+>>>  drop: condition test succeeded in ACL "drop"
+>>>  end of ACL "drop": DROP
+>>> accept: condition test yielded "drop" in ACL "nested_drop"

 >>> accept: endpass encountered - denying access
 >>> accept: endpass encountered - denying access

-LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <nested_drop@y>: forcibly dropped
-LOG: SMTP connection from [V4NET.9.8.7] closed by DROP in ACL
+LOG: H=(test) [V4NET.9.8.7] F=<x@y> rejected RCPT <nested_drop@y>: forcibly dropped
+LOG: SMTP connection from (test) [V4NET.9.8.7] closed by DROP in ACL

 >>> host in hosts_connection_nolog? no (end of list)
 LOG: SMTP connection from [V4NET.9.8.7]
 >>> host in host_lookup? no (option unset)
 >>> host in hosts_connection_nolog? no (end of list)
 LOG: SMTP connection from [V4NET.9.8.7]
 >>> host in host_lookup? no (option unset)


@@ -336,14 +402,18 @@ LOG: SMTP connection from [V4NET.9.8.7]
 >>> host in helo_verify_hosts? no (option unset)
 >>> host in helo_try_verify_hosts? no (option unset)
 >>> host in helo_accept_junk_hosts? no (option unset)
 >>> host in helo_verify_hosts? no (option unset)
 >>> host in helo_try_verify_hosts? no (option unset)
 >>> host in helo_accept_junk_hosts? no (option unset)

+>>> test in helo_lookup_domains? no (end of list)

 >>> using ACL "nested_drop_require"
 >>> using ACL "nested_drop_require"

->>> processing "require"
+>>> processing "require" (TESTSUITE/test-config 48)

 >>> check acl = drop
 >>> check acl = drop

->>> using ACL "drop"
->>> processing "drop"
->>> drop: condition test succeeded
->>> require: condition test yielded "drop"
-LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <nested_drop_require@y>: forcibly dropped
-LOG: SMTP connection from [V4NET.9.8.7] closed by DROP in ACL
+>>>  using ACL "drop"
+>>>  processing "drop" (TESTSUITE/test-config 41)
+>>>    message: forcibly dropped
+>>>  drop: condition test succeeded in ACL "drop"
+>>>  end of ACL "drop": DROP
+>>> require: condition test yielded "drop" in ACL "nested_drop_require"
+>>> end of ACL "nested_drop_require": not OK
+LOG: H=(test) [V4NET.9.8.7] F=<x@y> rejected RCPT <nested_drop_require@y>: forcibly dropped
+LOG: SMTP connection from (test) [V4NET.9.8.7] closed by DROP in ACL

 
 ******** SERVER ********
 
 ******** SERVER ********