X-Git-Url: https://git.exim.org/exim.git/blobdiff_plain/ea89660dc06ba1f1e919bd0d6564619831d64167..4b01271fa595a08e68ba8c58d6404e83623aa9c8:/test/stderr/0386 diff --git a/test/stderr/0386 b/test/stderr/0386 index 245137ea2..bd533157f 100644 --- a/test/stderr/0386 +++ b/test/stderr/0386 @@ -32,19 +32,24 @@ spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 SMTP>> 250 OK SMTP<< rcpt to:<1@b> -read ACL from file TESTSUITE/aux-fixed/0386.acl1 -processing "accept" -check hosts = : +using ACL "chk_rcpt" +processing "accept" (TESTSUITE/test-config 18) +check local_parts = 1 +1 in "1"? yes (matched "1") +check acl = TESTSUITE/aux-fixed/0386.acl1 + read ACL from file TESTSUITE/aux-fixed/0386.acl1 + processing "accept" (TESTSUITE/test-config 43) + check hosts = : host in ":"? no (end of list) -accept: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1" -processing "deny" -check local_parts = ^.*[@%!/|] + accept: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1" + processing "deny" (TESTSUITE/test-config 43) + check local_parts = ^.*[@%!/|] 1 in "^.*[@%!/|]"? no (end of list) -deny: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1" -processing "require" -l_message: Invalid sender - message: Couldn't verify the sender -check verify = sender/defer_ok + deny: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1" + processing "require" (TESTSUITE/test-config 43) + l_message: Invalid sender + message: Couldn't verify the sender + check verify = sender/defer_ok >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> Verifying x@y >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> @@ -64,13 +69,15 @@ domain = y routed by r1 router envelope to: x@y transport: t1 ------------ end verify ------------ -sender x@y verified ok -require: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1" -processing "deny" - message: No such user here -deny: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1" -end of ACL "TESTSUITE/aux-fixed/0386.acl1": DENY + ----------- end verify ------------ + sender x@y verified ok + require: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1" + processing "deny" (TESTSUITE/test-config 43) + message: No such user here + deny: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1" + end of ACL "TESTSUITE/aux-fixed/0386.acl1": DENY +accept: condition test failed in ACL "chk_rcpt" +accept: endpass encountered - denying access SMTP>> 550 No such user here LOG: MAIN REJECT H=[V4NET.9.8.7] F= rejected RCPT <1@b>: No such user here @@ -81,19 +88,24 @@ spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 SMTP>> 250 OK SMTP<< rcpt to:<1@b> -using ACL "TESTSUITE/aux-fixed/0386.acl1" -processing "accept" -check hosts = : +using ACL "chk_rcpt" +processing "accept" (TESTSUITE/test-config 18) +check local_parts = 1 +1 in "1"? yes (matched "1") +check acl = TESTSUITE/aux-fixed/0386.acl1 + using ACL "TESTSUITE/aux-fixed/0386.acl1" + processing "accept" (TESTSUITE/test-config 43) + check hosts = : host in ":"? no (end of list) -accept: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1" -processing "deny" -check local_parts = ^.*[@%!/|] + accept: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1" + processing "deny" (TESTSUITE/test-config 43) + check local_parts = ^.*[@%!/|] 1 in "^.*[@%!/|]"? no (end of list) -deny: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1" -processing "require" -l_message: Invalid sender - message: Couldn't verify the sender -check verify = sender/defer_ok + deny: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1" + processing "require" (TESTSUITE/test-config 43) + l_message: Invalid sender + message: Couldn't verify the sender + check verify = sender/defer_ok >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> Verifying x@y >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> @@ -112,13 +124,15 @@ domain = y routed by r1 router envelope to: x@y transport: t1 ------------ end verify ------------ -sender x@y verified ok -require: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1" -processing "deny" - message: No such user here -deny: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1" -end of ACL "TESTSUITE/aux-fixed/0386.acl1": DENY + ----------- end verify ------------ + sender x@y verified ok + require: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1" + processing "deny" (TESTSUITE/test-config 43) + message: No such user here + deny: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1" + end of ACL "TESTSUITE/aux-fixed/0386.acl1": DENY +accept: condition test failed in ACL "chk_rcpt" +accept: endpass encountered - denying access SMTP>> 550 No such user here LOG: MAIN REJECT H=[V4NET.9.8.7] F= rejected RCPT <1@b>: No such user here @@ -164,26 +178,44 @@ spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 SMTP>> 250 OK SMTP<< rcpt to:<2@b> -read ACL from file TESTSUITE/aux-fixed/0386.acl2 -processing "warn" - message: X-Warning: $sender_host_address is listed at $dnslist_domain\nX-Warning: $dnslist_text -l_message: found in $dnslist_domain: $dnslist_text -check dnslists = rbl.test.ex -DNS list check: rbl.test.ex +using ACL "chk_rcpt" +processing "accept" (TESTSUITE/test-config 18) +check local_parts = 1 +2 in "1"? no (end of list) +accept: condition test failed in ACL "chk_rcpt" +processing "accept" (TESTSUITE/test-config 21) +check local_parts = 2 +2 in "2"? yes (matched "2") +check acl = TESTSUITE/aux-fixed/0386.acl2 + read ACL from file TESTSUITE/aux-fixed/0386.acl2 + processing "warn" (TESTSUITE/test-config 43) + message: X-Warning: $sender_host_address is listed at $dnslist_domain\nX-Warning: $dnslist_text + l_message: found in $dnslist_domain: $dnslist_text + check dnslists = rbl.test.ex +dnslists check: rbl.test.ex new DNS lookup for 13.12.11.V4NET.rbl.test.ex DNS lookup of 13.12.11.V4NET.rbl.test.ex (A) using fakens +fresh-exec forking for fakens-search +postfork: fakens-search +fresh-exec forked for fakens-search: npppp DNS lookup of 13.12.11.V4NET.rbl.test.ex (A) succeeded +dnslists: wrote cache entry, ttl=3 DNS lookup for 13.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2) DNS lookup of 13.12.11.V4NET.rbl.test.ex (TXT) using fakens +fresh-exec forking for fakens-search +postfork: fakens-search +fresh-exec forked for fakens-search: npppp DNS lookup of 13.12.11.V4NET.rbl.test.ex (TXT) succeeded => that means V4NET.11.12.13 is listed at rbl.test.ex -warn: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2" + warn: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2" LOG: MAIN H=[V4NET.11.12.13] U=CALLER Warning: found in rbl.test.ex: This is a test blacklisting message created log directory TESTSUITE/spool/log -processing "accept" -accept: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2" -end of ACL "TESTSUITE/aux-fixed/0386.acl2": ACCEPT + processing "accept" (TESTSUITE/test-config 43) + accept: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2" + end of ACL "TESTSUITE/aux-fixed/0386.acl2": ACCEPT +accept: condition test succeeded in ACL "chk_rcpt" +end of ACL "chk_rcpt": ACCEPT SMTP>> 250 Accepted DSN: orcpt: NULL flags: 0 SMTP<< data @@ -206,20 +238,21 @@ P Received: from [V4NET.11.12.13] (ident=CALLER) X-Warning: V4NET.11.12.13 is listed at rbl.test.ex X-Warning: This is a test blacklisting message >> -calling local_scan(); timeout=300 -local_scan() returned 0 NULL -Writing spool header file: TESTSUITE/spool//input//hdr.pppp -DSN: Write SPOOL :-dsn_envid NULL -DSN: Write SPOOL :-dsn_ret 0 -DSN: Flags :0 -DSN: **** SPOOL_OUT - address: |2@b| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0 +Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00 +DSN: Write SPOOL: -dsn_envid NULL +DSN: Write SPOOL :-dsn_ret 0 +DSN: Flags: 0x0 +DSN: **** SPOOL_OUT - address: <2@b> errorsto: orcpt: dsn_flags: 0x0 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H Size of headers = sss LOG: MAIN <= x@y H=[V4NET.11.12.13] U=CALLER P=smtp S=sss SMTP>> 250 OK id=10HmaX-0005vi-00 search_tidyup called -exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmaX-0005vi-00 +fresh-exec forking for local-accept-delivery +postfork: local-accept-delivery +exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-0005vi-00 +fresh-exec forked for local-accept-delivery: npppp Exim version x.yz .... changed uid/gid: forcing real = effective uid=uuuu gid=EXIM_GID pid=pppp @@ -228,6 +261,7 @@ getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID configuration file is TESTSUITE/test-config trusted user admin user +dropping to exim gid; retaining priv uid seeking password data for user "CALLER": using cached result getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID set_process_info: pppp delivering specified messages @@ -244,21 +278,21 @@ Empty Tree recipients_count=1 **** SPOOL_IN - No additional fields body_linecount=1 message_linecount=7 -DSN: set orcpt: flags: 0 +DSN: set orcpt: flags: 0x0 Delivery address list: 2@b -locking TESTSUITE/spool/db/retry.lockfile -locked TESTSUITE/spool/db/retry.lockfile -EXIM_DBOPEN: file dir flags=O_RDONLY -returned from EXIM_DBOPEN: (nil) -ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim -failed to open DB file TESTSUITE/spool/db/retry.lockfile: No such file or directory + locking TESTSUITE/spool/db/retry.lockfile + locked TESTSUITE/spool/db/retry.lockfile + EXIM_DBOPEN: file dir flags=O_RDONLY + returned from EXIM_DBOPEN: (nil) + ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim + failed to open DB file TESTSUITE/spool/db/retry.lockfile: No such file or directory no retry data available >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> Considering: 2@b unique = 2@b -no domain retry record -no address retry record +no domain retry record +no address retry record 2@b: queued for routing >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> routing 2@b @@ -285,13 +319,15 @@ After routing: search_tidyup called >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>> --------> 2@b <-------- -locking TESTSUITE/spool/db/retry.lockfile -locked TESTSUITE/spool/db/retry.lockfile -EXIM_DBOPEN: file dir flags=O_RDONLY -returned from EXIM_DBOPEN: (nil) -failed to open DB file TESTSUITE/spool/db/retry: No such file or directory + locking TESTSUITE/spool/db/retry.lockfile + locked TESTSUITE/spool/db/retry.lockfile + EXIM_DBOPEN: file dir flags=O_RDONLY + returned from EXIM_DBOPEN: (nil) + failed to open DB file TESTSUITE/spool/db/retry: No such file or directory no retry data available search_tidyup called +local-accept-delivery forking for delivery-local +postfork: delivery-local changed uid/gid: local delivery to 2 <2@b> transport=t1 uid=CALLER_UID gid=CALLER_GID pid=pppp home=NULL current=/ @@ -314,6 +350,7 @@ writing data block fd=dddd size=sss timeout=0 writing data block fd=dddd size=sss timeout=0 appendfile yields 0 with errno=dd more_errno=dd search_tidyup called +local-accept-delivery forked for delivery-local: npppp journalling 2@b t1 transport returned OK for 2@b post-process 2@b (0) @@ -333,7 +370,7 @@ end of retry processing DSN: processing router : r1 DSN: processing successful delivery address: 2@b DSN: Sender_address: x@y -DSN: orcpt: NULL flags: 0 +DSN: orcpt: NULL flags: 0x0 DSN: envid: NULL ret: 0 DSN: Final recipient: 2@b DSN: Remote SMTP server supports DSN: 0 @@ -352,21 +389,32 @@ spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 SMTP>> 250 OK SMTP<< rcpt to:<2@b> -using ACL "TESTSUITE/aux-fixed/0386.acl2" -processing "warn" - message: X-Warning: $sender_host_address is listed at $dnslist_domain\nX-Warning: $dnslist_text -l_message: found in $dnslist_domain: $dnslist_text -check dnslists = rbl.test.ex -DNS list check: rbl.test.ex -using result of previous DNS lookup +using ACL "chk_rcpt" +processing "accept" (TESTSUITE/test-config 18) +check local_parts = 1 +2 in "1"? no (end of list) +accept: condition test failed in ACL "chk_rcpt" +processing "accept" (TESTSUITE/test-config 21) +check local_parts = 2 +2 in "2"? yes (matched "2") +check acl = TESTSUITE/aux-fixed/0386.acl2 + using ACL "TESTSUITE/aux-fixed/0386.acl2" + processing "warn" (TESTSUITE/test-config 43) + message: X-Warning: $sender_host_address is listed at $dnslist_domain\nX-Warning: $dnslist_text + l_message: found in $dnslist_domain: $dnslist_text + check dnslists = rbl.test.ex +dnslists check: rbl.test.ex +dnslists: using result of previous lookup DNS lookup for 13.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2) => that means V4NET.11.12.13 is listed at rbl.test.ex -warn: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2" + warn: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2" LOG: MAIN H=[V4NET.11.12.13] U=CALLER Warning: found in rbl.test.ex: This is a test blacklisting message -processing "accept" -accept: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2" -end of ACL "TESTSUITE/aux-fixed/0386.acl2": ACCEPT + processing "accept" (TESTSUITE/test-config 43) + accept: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2" + end of ACL "TESTSUITE/aux-fixed/0386.acl2": ACCEPT +accept: condition test succeeded in ACL "chk_rcpt" +end of ACL "chk_rcpt": ACCEPT SMTP>> 250 Accepted DSN: orcpt: NULL flags: 0 SMTP<< data @@ -389,20 +437,21 @@ P Received: from [V4NET.11.12.13] (ident=CALLER) X-Warning: V4NET.11.12.13 is listed at rbl.test.ex X-Warning: This is a test blacklisting message >> -calling local_scan(); timeout=300 -local_scan() returned 0 NULL -Writing spool header file: TESTSUITE/spool//input//hdr.pppp -DSN: Write SPOOL :-dsn_envid NULL -DSN: Write SPOOL :-dsn_ret 0 -DSN: Flags :0 -DSN: **** SPOOL_OUT - address: |2@b| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0 +Writing spool header file: TESTSUITE/spool//input//hdr.10HmaY-0005vi-00 +DSN: Write SPOOL: -dsn_envid NULL +DSN: Write SPOOL :-dsn_ret 0 +DSN: Flags: 0x0 +DSN: **** SPOOL_OUT - address: <2@b> errorsto: orcpt: dsn_flags: 0x0 Renaming spool header file: TESTSUITE/spool//input//10HmaY-0005vi-00-H Size of headers = sss LOG: MAIN <= x@y H=[V4NET.11.12.13] U=CALLER P=smtp S=sss SMTP>> 250 OK id=10HmaY-0005vi-00 search_tidyup called -exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmaY-0005vi-00 +fresh-exec forking for local-accept-delivery +postfork: local-accept-delivery +exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaY-0005vi-00 +fresh-exec forked for local-accept-delivery: npppp Exim version x.yz .... changed uid/gid: forcing real = effective uid=uuuu gid=EXIM_GID pid=pppp @@ -411,6 +460,7 @@ getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID configuration file is TESTSUITE/test-config trusted user admin user +dropping to exim gid; retaining priv uid seeking password data for user "CALLER": using cached result getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID set_process_info: pppp delivering specified messages @@ -427,20 +477,20 @@ Empty Tree recipients_count=1 **** SPOOL_IN - No additional fields body_linecount=1 message_linecount=7 -DSN: set orcpt: flags: 0 +DSN: set orcpt: flags: 0x0 Delivery address list: 2@b -locking TESTSUITE/spool/db/retry.lockfile -locked TESTSUITE/spool/db/retry.lockfile -EXIM_DBOPEN: file dir flags=O_RDONLY -returned from EXIM_DBOPEN: (nil) -failed to open DB file TESTSUITE/spool/db/retry: No such file or directory + locking TESTSUITE/spool/db/retry.lockfile + locked TESTSUITE/spool/db/retry.lockfile + EXIM_DBOPEN: file dir flags=O_RDONLY + returned from EXIM_DBOPEN: (nil) + failed to open DB file TESTSUITE/spool/db/retry: No such file or directory no retry data available >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> Considering: 2@b unique = 2@b -no domain retry record -no address retry record +no domain retry record +no address retry record 2@b: queued for routing >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> routing 2@b @@ -467,13 +517,15 @@ After routing: search_tidyup called >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>> --------> 2@b <-------- -locking TESTSUITE/spool/db/retry.lockfile -locked TESTSUITE/spool/db/retry.lockfile -EXIM_DBOPEN: file dir flags=O_RDONLY -returned from EXIM_DBOPEN: (nil) -failed to open DB file TESTSUITE/spool/db/retry: No such file or directory + locking TESTSUITE/spool/db/retry.lockfile + locked TESTSUITE/spool/db/retry.lockfile + EXIM_DBOPEN: file dir flags=O_RDONLY + returned from EXIM_DBOPEN: (nil) + failed to open DB file TESTSUITE/spool/db/retry: No such file or directory no retry data available search_tidyup called +local-accept-delivery forking for delivery-local +postfork: delivery-local changed uid/gid: local delivery to 2 <2@b> transport=t1 uid=CALLER_UID gid=CALLER_GID pid=pppp home=NULL current=/ @@ -496,6 +548,7 @@ writing data block fd=dddd size=sss timeout=0 writing data block fd=dddd size=sss timeout=0 appendfile yields 0 with errno=dd more_errno=dd search_tidyup called +local-accept-delivery forked for delivery-local: npppp journalling 2@b t1 transport returned OK for 2@b post-process 2@b (0) @@ -515,7 +568,7 @@ end of retry processing DSN: processing router : r1 DSN: processing successful delivery address: 2@b DSN: Sender_address: x@y -DSN: orcpt: NULL flags: 0 +DSN: orcpt: NULL flags: 0x0 DSN: envid: NULL ret: 0 DSN: Final recipient: 2@b DSN: Remote SMTP server supports DSN: 0