X-Git-Url: https://git.exim.org/exim.git/blobdiff_plain/54c5ebb14ead7439af300e9d7d9d7d2ba29ff32e..ee549a2ed04164407f4f897be3bf545f32579c5c:/test/stderr/0386?ds=inline diff --git a/test/stderr/0386 b/test/stderr/0386 index 3b9c6d950..fedf1e3d3 100644 --- a/test/stderr/0386 +++ b/test/stderr/0386 @@ -1,12 +1,16 @@ Exim version x.yz .... +Hints DB: changed uid/gid: forcing real = effective - uid=uuuu gid=CALLER_GID pid=pppp + uid=uuuu gid=CALLER_GID pid=p1234 +seeking password data for user "CALLER": cache not available +getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID configuration file is TESTSUITE/test-config trusted user admin user changed uid/gid: privilege not needed - uid=EXIM_UID gid=EXIM_GID pid=pppp -finduser used cached passwd data for CALLER + uid=EXIM_UID gid=EXIM_GID pid=p1234 +seeking password data for user "CALLER": using cached result +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] @@ -22,22 +26,48 @@ 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) -SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000 +SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000 smtp_setup_msg entered +SMTP<< helo test +test in helo_lookup_domains? + list element: @ + list element: @[] +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: -SMTP>> 250 OK +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:<1@b> -read ACL from file TESTSUITE/aux-fixed/0386.acl1 -processing "accept" -check hosts = : -host in ":"? no (end of list) -accept: condition test failed -processing "deny" -check local_parts = ^.*[@%!/|] -1 in "^.*[@%!/|]"? no (end of list) -deny: condition test failed -processing "require" -check verify = sender/defer_ok +using ACL "chk_rcpt" +processing ACL chk_rcpt "accept" (TESTSUITE/test-config 18) +check local_parts = 1 +1 in "1"? + list element: 1 + 1 in "1"? yes (matched "1") +check acl = TESTSUITE/aux-fixed/0386.acl1 + read ACL from file TESTSUITE/aux-fixed/0386.acl1 + processing ACL TESTSUITE/aux-fixed/0386.acl1 "accept" (TESTSUITE/test-config 18) + check hosts = : + host in ":"? + list element: + host in ":"? no (end of list) + accept: condition test failed in ACL TESTSUITE/aux-fixed/0386.acl1 + processing ACL TESTSUITE/aux-fixed/0386.acl1 "deny" (TESTSUITE/test-config 18) + check local_parts = ^.*[@%!/|] + 1 in "^.*[@%!/|]"? + list element: ^.*[@%!/|] + compiled caseless RE '^.*[@%!/|]' not found in local cache + compiled RE '^.*[@%!/|]' saved in local cache + 1 in "^.*[@%!/|]"? no (end of list) + deny: condition test failed in ACL TESTSUITE/aux-fixed/0386.acl1 + processing ACL TESTSUITE/aux-fixed/0386.acl1 "require" (TESTSUITE/test-config 18) + l_message: Invalid sender + message: Couldn't verify the sender + check verify = sender/defer_ok >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> Verifying x@y >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> @@ -53,34 +83,54 @@ set transport t1 queued for t1 transport: local_part = x domain = y errors_to=NULL - domain_data=NULL localpart_data=NULL + domain_data=NULL local_part_data=NULL routed by r1 router envelope to: x@y transport: t1 ------------ end verify ------------ -sender x@y verified ok -require: condition test succeeded -processing "deny" -deny: condition test succeeded -SMTP>> 550 No such user here + ----------- end verify ------------ + sender x@y verified ok + require: condition test succeeded in ACL TESTSUITE/aux-fixed/0386.acl1 + processing ACL TESTSUITE/aux-fixed/0386.acl1 "deny" (TESTSUITE/test-config 18) + 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 + H=(test) [V4NET.9.8.7] F= rejected RCPT <1@b>: No such user here SMTP<< rset -SMTP>> 250 Reset OK +SMTP>> 250 Reset OK SMTP<< mail from: -SMTP>> 250 OK +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:<1@b> -using ACL "TESTSUITE/aux-fixed/0386.acl1" -processing "accept" -check hosts = : -host in ":"? no (end of list) -accept: condition test failed -processing "deny" -check local_parts = ^.*[@%!/|] -1 in "^.*[@%!/|]"? no (end of list) -deny: condition test failed -processing "require" -check verify = sender/defer_ok +using ACL "chk_rcpt" +processing ACL chk_rcpt "accept" (TESTSUITE/test-config 18) +check local_parts = 1 +1 in "1"? + list element: 1 + 1 in "1"? yes (matched "1") +check acl = TESTSUITE/aux-fixed/0386.acl1 + using ACL "TESTSUITE/aux-fixed/0386.acl1" + processing ACL TESTSUITE/aux-fixed/0386.acl1 "accept" (TESTSUITE/test-config 18) + check hosts = : + host in ":"? + list element: + host in ":"? no (end of list) + accept: condition test failed in ACL TESTSUITE/aux-fixed/0386.acl1 + processing ACL TESTSUITE/aux-fixed/0386.acl1 "deny" (TESTSUITE/test-config 18) + check local_parts = ^.*[@%!/|] + 1 in "^.*[@%!/|]"? + list element: ^.*[@%!/|] + compiled caseless RE '^.*[@%!/|]' found in local cache + 1 in "^.*[@%!/|]"? no (end of list) + deny: condition test failed in ACL TESTSUITE/aux-fixed/0386.acl1 + processing ACL TESTSUITE/aux-fixed/0386.acl1 "require" (TESTSUITE/test-config 18) + l_message: Invalid sender + message: Couldn't verify the sender + check verify = sender/defer_ok >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> Verifying x@y >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> @@ -95,38 +145,47 @@ r1 router called for x@y queued for t1 transport: local_part = x domain = y errors_to=NULL - domain_data=NULL localpart_data=NULL + domain_data=NULL local_part_data=NULL routed by r1 router envelope to: x@y transport: t1 ------------ end verify ------------ -sender x@y verified ok -require: condition test succeeded -processing "deny" -deny: condition test succeeded -SMTP>> 550 No such user here + ----------- end verify ------------ + sender x@y verified ok + require: condition test succeeded in ACL TESTSUITE/aux-fixed/0386.acl1 + processing ACL TESTSUITE/aux-fixed/0386.acl1 "deny" (TESTSUITE/test-config 18) + 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 + H=(test) [V4NET.9.8.7] F= rejected RCPT <1@b>: No such user here SMTP<< quit -SMTP>> 221 myhost.test.ex closing connection +SMTP>> 221 myhost.test.ex closing connection LOG: smtp_connection MAIN - SMTP connection from [V4NET.9.8.7] closed by QUIT + SMTP connection from (test) [V4NET.9.8.7] D=qqs closed by QUIT search_tidyup called ->>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>> +>>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>> Exim version x.yz .... +Hints DB: changed uid/gid: forcing real = effective - uid=uuuu gid=CALLER_GID pid=pppp + uid=uuuu gid=CALLER_GID pid=p1235 +seeking password data for user "CALLER": cache not available +getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID configuration file is TESTSUITE/test-config trusted user admin user changed uid/gid: privilege not needed - uid=EXIM_UID gid=EXIM_GID pid=pppp -finduser used cached passwd data for CALLER + uid=EXIM_UID gid=EXIM_GID pid=p1235 +seeking password data for user "CALLER": using cached result +getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME sender address = NULL sender_fullhost = [V4NET.11.12.13] sender_rcvhost = [V4NET.11.12.13] (ident=CALLER) set_process_info: pppp handling incoming connection from [V4NET.11.12.13] via -oMa +set_process_info: pppp accepting a local SMTP message from host in hosts_connection_nolog? no (option unset) LOG: smtp_connection MAIN SMTP connection from CALLER @@ -138,90 +197,129 @@ 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) -SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000 +SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000 smtp_setup_msg entered +SMTP<< helo test +test in helo_lookup_domains? + list element: @ + list element: @[] +test in helo_lookup_domains? no (end of list) +sender_fullhost = (test) [V4NET.11.12.13] +sender_rcvhost = [V4NET.11.12.13] (helo=test ident=CALLER) +set_process_info: pppp handling incoming connection from (test) [V4NET.11.12.13] U=CALLER +SMTP>> 250 myhost.test.ex Hello CALLER at test [V4NET.11.12.13] SMTP<< mail from: -SMTP>> 250 OK +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:<2@b> -read ACL from file TESTSUITE/aux-fixed/0386.acl2 -processing "warn" -check dnslists = rbl.test.ex -DNS list check: rbl.test.ex +using ACL "chk_rcpt" +processing ACL chk_rcpt "accept" (TESTSUITE/test-config 18) +check local_parts = 1 +2 in "1"? + list element: 1 +2 in "1"? no (end of list) +accept: condition test failed in ACL chk_rcpt +processing ACL chk_rcpt "accept" (TESTSUITE/test-config 21) +check local_parts = 2 +2 in "2"? + list element: 2 + 2 in "2"? yes (matched "2") +check acl = TESTSUITE/aux-fixed/0386.acl2 + read ACL from file TESTSUITE/aux-fixed/0386.acl2 + processing ACL TESTSUITE/aux-fixed/0386.acl2 "warn" (TESTSUITE/test-config 21) + 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 -DNS lookup of 13.12.11.V4NET.rbl.test.ex (A) succeeded + DNS lookup of 13.12.11.V4NET.rbl.test.ex (A) using fakens + 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 -DNS lookup of 13.12.11.V4NET.rbl.test.ex (TXT) succeeded + DNS lookup of 13.12.11.V4NET.rbl.test.ex (TXT) using fakens + 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 + 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 + H=(test) [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 -SMTP>> 250 Accepted + processing ACL TESTSUITE/aux-fixed/0386.acl2 "accept" (TESTSUITE/test-config 21) + 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 SMTP<< data -SMTP>> 354 Enter message, ending with "." on a line by itself +SMTP>> 354 Enter message, ending with "." on a line by itself search_tidyup called -host in ignore_fromline_hosts? no (option unset) >>Headers received: +qualify & rewrite recipients list +rewrite rules on sender address +qualify and rewrite headers search_tidyup called >>Headers after rewriting and local additions: -Data file written for message 10HmaX-0005vi-00 +Data file name: TESTSUITE/spool//input//10HmaX-000000005vi-0000-D +Data file written for message 10HmaX-000000005vi-0000 >>Generated Received: header line -P Received: from [V4NET.11.12.13] (ident=CALLER) +P Received: from [V4NET.11.12.13] (helo=test ident=CALLER) by myhost.test.ex with smtp (Exim x.yz) (envelope-from ) - id 10HmaX-0005vi-00 - for 2@b; Tue, 2 Mar 1999 09:44:33 +0000 + id 10HmaX-000000005vi-0000 + for 2@b; + Tue, 2 Mar 1999 09:44:33 +0000 >>Headers added by MAIL or RCPT ACL: 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 +Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-000000005vi-0000 +DSN: **** SPOOL_OUT - address: <2@b> errorsto: orcpt: dsn_flags: 0x0 +Renaming spool header file: TESTSUITE/spool//input//10HmaX-000000005vi-0000-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 + <= x@y H=(test) [V4NET.11.12.13] U=CALLER P=smtp S=sss +receive_msg: tidyup +SMTP>> 250 OK id=10HmaX-000000005vi-0000 search_tidyup called -exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmaX-0005vi-00 +exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-000000005vi-0000 Exim version x.yz .... +Hints DB: changed uid/gid: forcing real = effective - uid=uuuu gid=EXIM_GID pid=pppp + uid=uuuu gid=EXIM_GID pid=p1236 +seeking password data for user "CALLER": cache not available +getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID configuration file is TESTSUITE/test-config trusted user admin user -finduser used cached passwd data for CALLER +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 -set_process_info: pppp delivering 10HmaX-0005vi-00 -reading spool file 10HmaX-0005vi-00-H +set_process_info: pppp delivering 10HmaX-000000005vi-0000 +Trying spool file TESTSUITE/spool//input//10HmaX-000000005vi-0000-D +reading spool file 10HmaX-000000005vi-0000-H user=CALLER uid=CALLER_UID gid=CALLER_GID sender=x@y -sender_fullhost = [V4NET.11.12.13] -sender_rcvhost = [V4NET.11.12.13] (ident=CALLER) +sender_fullhost = (test) [V4NET.11.12.13] +sender_rcvhost = [V4NET.11.12.13] (helo=test ident=CALLER) sender_local=0 ident=CALLER Non-recipients: -Empty Tree + Empty Tree ---- End of tree ---- recipients_count=1 -body_linecount=1 message_linecount=7 +**** SPOOL_IN - No additional fields +body_linecount=1 message_linecount=8 +DSN: set orcpt: flags: 0x0 Delivery address list: 2@b -locking TESTSUITE/spool/db/retry.lockfile -locked TESTSUITE/spool/db/retry.lockfile -EXIM_DBOPEN(TESTSUITE/spool/db/retry) -returned from EXIM_DBOPEN -ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim no retry data available >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> Considering: 2@b unique = 2@b -no domain retry record -no address retry record +checking router retry status + no domain retry record + no address retry record 2@b: queued for routing >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> routing 2@b @@ -234,7 +332,7 @@ set transport t1 queued for t1 transport: local_part = 2 domain = b errors_to=NULL - domain_data=NULL localpart_data=NULL + domain_data=NULL local_part_data=NULL routed by r1 router envelope to: 2@b transport: t1 @@ -248,16 +346,12 @@ After routing: search_tidyup called >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>> --------> 2@b <-------- -locking TESTSUITE/spool/db/retry.lockfile -locked TESTSUITE/spool/db/retry.lockfile -EXIM_DBOPEN(TESTSUITE/spool/db/retry) -returned from EXIM_DBOPEN no retry data available search_tidyup called changed uid/gid: local delivery to 2 <2@b> transport=t1 - uid=CALLER_UID gid=CALLER_GID pid=pppp + uid=CALLER_UID gid=CALLER_GID pid=p1237 home=NULL current=/ -set_process_info: pppp delivering 10HmaX-0005vi-00 to 2 using t1 +set_process_info: pppp delivering 10HmaX-000000005vi-0000 to 2 using t1 appendfile transport entered appendfile: mode=600 notify_comsat=0 quota=0 warning=0 file=TESTSUITE/test-mail/2 format=unix @@ -265,16 +359,19 @@ appendfile: mode=600 notify_comsat=0 quota=0 warning=0 message_suffix=\n maildir_use_size_file=no locking by lockfile fcntl +below-home: de-tainting path 'TESTSUITE/test-mail/2' lock name: TESTSUITE/test-mail/2.lock hitch name: TESTSUITE/test-mail/2.lock.test.ex.dddddddd.pppppppp lock file created mailbox TESTSUITE/test-mail/2 is locked writing to file TESTSUITE/test-mail/2 -writing data block fd=10 size=sss timeout=0 -writing data block fd=10 size=sss timeout=0 -writing data block fd=10 size=sss timeout=0 +writing data block fd=dddd size=sss timeout=0 +cannot use sendfile for body: spoolfile not wireformat +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 +>>>>>>>>>>>>>>>> Exim pid=p1237 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>> journalling 2@b t1 transport returned OK for 2@b post-process 2@b (0) @@ -283,99 +380,137 @@ LOG: MAIN => 2 <2@b> R=r1 T=t1 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>> changed uid/gid: post-delivery tidying - uid=EXIM_UID gid=EXIM_GID pid=pppp -set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00 + uid=EXIM_UID gid=EXIM_GID pid=p1236 +set_process_info: pppp tidying up after delivering 10HmaX-000000005vi-0000 Processing retry items -Succeeded addresses: -2@b: no retry items -Failed addresses: -Deferred addresses: + Succeeded addresses: + 2@b: no retry items + Failed addresses: + Deferred addresses: end of retry processing +DSN: processing router : r1 +DSN: processing successful delivery address: 2@b +DSN: Sender_address: x@y +DSN: orcpt: NULL flags: 0x0 +DSN: envid: NULL ret: 0 +DSN: Final recipient: 2@b +DSN: Remote SMTP server supports DSN: 0 +DSN: not sending DSN success message LOG: MAIN Completed -end delivery of 10HmaX-0005vi-00 +end delivery of 10HmaX-000000005vi-0000 search_tidyup called search_tidyup called ->>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>> +>>>>>>>>>>>>>>>> Exim pid=p1236 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>> smtp_setup_msg entered SMTP<< rset -SMTP>> 250 Reset OK +SMTP>> 250 Reset OK SMTP<< mail from: -SMTP>> 250 OK +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:<2@b> -using ACL "TESTSUITE/aux-fixed/0386.acl2" -processing "warn" -check dnslists = rbl.test.ex -DNS list check: rbl.test.ex -using result of previous DNS lookup +using ACL "chk_rcpt" +processing ACL chk_rcpt "accept" (TESTSUITE/test-config 18) +check local_parts = 1 +2 in "1"? + list element: 1 +2 in "1"? no (end of list) +accept: condition test failed in ACL chk_rcpt +processing ACL chk_rcpt "accept" (TESTSUITE/test-config 21) +check local_parts = 2 +2 in "2"? + list element: 2 + 2 in "2"? yes (matched "2") +check acl = TESTSUITE/aux-fixed/0386.acl2 + using ACL "TESTSUITE/aux-fixed/0386.acl2" + processing ACL TESTSUITE/aux-fixed/0386.acl2 "warn" (TESTSUITE/test-config 21) + 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 + 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 -SMTP>> 250 Accepted + H=(test) [V4NET.11.12.13] U=CALLER Warning: found in rbl.test.ex: This is a test blacklisting message + processing ACL TESTSUITE/aux-fixed/0386.acl2 "accept" (TESTSUITE/test-config 21) + 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 SMTP<< data -SMTP>> 354 Enter message, ending with "." on a line by itself +SMTP>> 354 Enter message, ending with "." on a line by itself search_tidyup called -host in ignore_fromline_hosts? no (option unset) >>Headers received: +qualify & rewrite recipients list +rewrite rules on sender address +qualify and rewrite headers search_tidyup called >>Headers after rewriting and local additions: -Data file written for message 10HmaY-0005vi-00 +Data file name: TESTSUITE/spool//input//10HmaY-000000005vi-0000-D +Data file written for message 10HmaY-000000005vi-0000 >>Generated Received: header line -P Received: from [V4NET.11.12.13] (ident=CALLER) +P Received: from [V4NET.11.12.13] (helo=test ident=CALLER) by myhost.test.ex with smtp (Exim x.yz) (envelope-from ) - id 10HmaY-0005vi-00 - for 2@b; Tue, 2 Mar 1999 09:44:33 +0000 + id 10HmaY-000000005vi-0000 + for 2@b; + Tue, 2 Mar 1999 09:44:33 +0000 >>Headers added by MAIL or RCPT ACL: 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 +Writing spool header file: TESTSUITE/spool//input//hdr.10HmaY-000000005vi-0000 +DSN: **** SPOOL_OUT - address: <2@b> errorsto: orcpt: dsn_flags: 0x0 +Renaming spool header file: TESTSUITE/spool//input//10HmaY-000000005vi-0000-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 + <= x@y H=(test) [V4NET.11.12.13] U=CALLER P=smtp S=sss +receive_msg: tidyup +SMTP>> 250 OK id=10HmaY-000000005vi-0000 search_tidyup called -exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmaY-0005vi-00 +exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaY-000000005vi-0000 Exim version x.yz .... +Hints DB: changed uid/gid: forcing real = effective - uid=uuuu gid=EXIM_GID pid=pppp + uid=uuuu gid=EXIM_GID pid=p1238 +seeking password data for user "CALLER": cache not available +getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID configuration file is TESTSUITE/test-config trusted user admin user -finduser used cached passwd data for CALLER +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 -set_process_info: pppp delivering 10HmaY-0005vi-00 -reading spool file 10HmaY-0005vi-00-H +set_process_info: pppp delivering 10HmaY-000000005vi-0000 +Trying spool file TESTSUITE/spool//input//10HmaY-000000005vi-0000-D +reading spool file 10HmaY-000000005vi-0000-H user=CALLER uid=CALLER_UID gid=CALLER_GID sender=x@y -sender_fullhost = [V4NET.11.12.13] -sender_rcvhost = [V4NET.11.12.13] (ident=CALLER) +sender_fullhost = (test) [V4NET.11.12.13] +sender_rcvhost = [V4NET.11.12.13] (helo=test ident=CALLER) sender_local=0 ident=CALLER Non-recipients: -Empty Tree + Empty Tree ---- End of tree ---- recipients_count=1 -body_linecount=1 message_linecount=7 +**** SPOOL_IN - No additional fields +body_linecount=1 message_linecount=8 +DSN: set orcpt: flags: 0x0 Delivery address list: 2@b -locking TESTSUITE/spool/db/retry.lockfile -locked TESTSUITE/spool/db/retry.lockfile -EXIM_DBOPEN(TESTSUITE/spool/db/retry) -returned from EXIM_DBOPEN no retry data available >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> Considering: 2@b unique = 2@b -no domain retry record -no address retry record +checking router retry status + no domain retry record + no address retry record 2@b: queued for routing >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> routing 2@b @@ -388,7 +523,7 @@ set transport t1 queued for t1 transport: local_part = 2 domain = b errors_to=NULL - domain_data=NULL localpart_data=NULL + domain_data=NULL local_part_data=NULL routed by r1 router envelope to: 2@b transport: t1 @@ -402,16 +537,12 @@ After routing: search_tidyup called >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>> --------> 2@b <-------- -locking TESTSUITE/spool/db/retry.lockfile -locked TESTSUITE/spool/db/retry.lockfile -EXIM_DBOPEN(TESTSUITE/spool/db/retry) -returned from EXIM_DBOPEN no retry data available search_tidyup called changed uid/gid: local delivery to 2 <2@b> transport=t1 - uid=CALLER_UID gid=CALLER_GID pid=pppp + uid=CALLER_UID gid=CALLER_GID pid=p1239 home=NULL current=/ -set_process_info: pppp delivering 10HmaY-0005vi-00 to 2 using t1 +set_process_info: pppp delivering 10HmaY-000000005vi-0000 to 2 using t1 appendfile transport entered appendfile: mode=600 notify_comsat=0 quota=0 warning=0 file=TESTSUITE/test-mail/2 format=unix @@ -419,16 +550,19 @@ appendfile: mode=600 notify_comsat=0 quota=0 warning=0 message_suffix=\n maildir_use_size_file=no locking by lockfile fcntl +below-home: de-tainting path 'TESTSUITE/test-mail/2' lock name: TESTSUITE/test-mail/2.lock hitch name: TESTSUITE/test-mail/2.lock.test.ex.dddddddd.pppppppp lock file created mailbox TESTSUITE/test-mail/2 is locked writing to file TESTSUITE/test-mail/2 -writing data block fd=10 size=sss timeout=0 -writing data block fd=10 size=sss timeout=0 -writing data block fd=10 size=sss timeout=0 +writing data block fd=dddd size=sss timeout=0 +cannot use sendfile for body: spoolfile not wireformat +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 +>>>>>>>>>>>>>>>> Exim pid=p1239 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>> journalling 2@b t1 transport returned OK for 2@b post-process 2@b (0) @@ -437,24 +571,32 @@ LOG: MAIN => 2 <2@b> R=r1 T=t1 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>> changed uid/gid: post-delivery tidying - uid=EXIM_UID gid=EXIM_GID pid=pppp -set_process_info: pppp tidying up after delivering 10HmaY-0005vi-00 + uid=EXIM_UID gid=EXIM_GID pid=p1238 +set_process_info: pppp tidying up after delivering 10HmaY-000000005vi-0000 Processing retry items -Succeeded addresses: -2@b: no retry items -Failed addresses: -Deferred addresses: + Succeeded addresses: + 2@b: no retry items + Failed addresses: + Deferred addresses: end of retry processing +DSN: processing router : r1 +DSN: processing successful delivery address: 2@b +DSN: Sender_address: x@y +DSN: orcpt: NULL flags: 0x0 +DSN: envid: NULL ret: 0 +DSN: Final recipient: 2@b +DSN: Remote SMTP server supports DSN: 0 +DSN: not sending DSN success message LOG: MAIN Completed -end delivery of 10HmaY-0005vi-00 +end delivery of 10HmaY-000000005vi-0000 search_tidyup called search_tidyup called ->>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>> +>>>>>>>>>>>>>>>> Exim pid=p1238 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>> smtp_setup_msg entered SMTP<< quit -SMTP>> 221 myhost.test.ex closing connection +SMTP>> 221 myhost.test.ex closing connection LOG: smtp_connection MAIN - SMTP connection from CALLER closed by QUIT + SMTP connection from CALLER D=qqs closed by QUIT search_tidyup called ->>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>> +>>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>