X-Git-Url: https://git.exim.org/exim.git/blobdiff_plain/58fc5fb2eec65bc0b1c7f5e571e3c534cf008b88..9bf74b9c298f6968417fe88a68ccdf2179db1403:/test/stderr/0388?ds=sidebyside diff --git a/test/stderr/0388 b/test/stderr/0388 index e8269e035..78be65faf 100644 --- a/test/stderr/0388 +++ b/test/stderr/0388 @@ -1,30 +1,30 @@ 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 configuration file is TESTSUITE/test-config admin user -DSN: r0 propagating DSN -DSN: r1 propagating DSN +dropping to exim gid; retaining priv uid set_process_info: pppp delivering specified messages -set_process_info: pppp delivering 10HmaX-0005vi-00 -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 +set_process_info: pppp delivering 10HmaX-000000005vi-0000 + 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: x@y unique = x@y -no domain retry record -no address retry record +no domain retry record +no address retry record x@y: queued for routing >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> routing x@y --------> r0 router <-------- local_part=x domain=y checking local_parts -x in "CALLER"? no (end of list) +x in local_parts? + list element: CALLER +x in local_parts? no (end of list) r0 router skipped: local_parts mismatch --------> r1 router <-------- local_part=x domain=y @@ -32,9 +32,11 @@ calling r1 router r1 router called for x@y domain = y route_item = * "127.0.0.1 : V4NET.0.0.0" -y in "*"? yes (matched "*") -original list of hosts = "127.0.0.1 : V4NET.0.0.0" options = -expanded list of hosts = "127.0.0.1 : V4NET.0.0.0" options = +y in "*"? + list element: * + y in "*"? yes (matched "*") +original list of hosts = '127.0.0.1 : V4NET.0.0.0' options = '' +expanded list of hosts = '127.0.0.1 : V4NET.0.0.0' options = '' set transport smtp finding IP address for 127.0.0.1 calling host_find_byname @@ -55,7 +57,7 @@ get[host|ipnode]byname[2] looked up these IP addresses: queued for smtp 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: smtp @@ -71,114 +73,138 @@ After routing: search_tidyup called search_tidyup called changed uid/gid: remote delivery to x@y with transport=smtp - uid=EXIM_UID gid=EXIM_GID pid=pppp -set_process_info: pppp delivering 10HmaX-0005vi-00 using smtp -checking status of 127.0.0.1 -locking TESTSUITE/spool/db/retry.lockfile -locked TESTSUITE/spool/db/retry.lockfile -EXIM_DBOPEN(TESTSUITE/spool/db/retry) -returned from EXIM_DBOPEN + uid=EXIM_UID gid=EXIM_GID pid=p1235 +set_process_info: pppp delivering 10HmaX-000000005vi-0000 using smtp +checking retry status of 127.0.0.1 + 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 127.0.0.1 in serialize_hosts? no (option unset) -set_process_info: pppp delivering 10HmaX-0005vi-00 to 127.0.0.1 [127.0.0.1] (x@y) -Connecting to 127.0.0.1 [127.0.0.1]:1224 ... connected +set_process_info: pppp delivering 10HmaX-000000005vi-0000 to 127.0.0.1 [127.0.0.1]:PORT_S (x@y) +Connecting to 127.0.0.1 [127.0.0.1]:PORT_S ... + 127.0.0.1 in hosts_try_fastopen? + list element: +connected SMTP<< 220 Server ready 127.0.0.1 in hosts_avoid_esmtp? no (option unset) SMTP>> EHLO myhost.test.ex -cmd buf flush 21 bytes +cmd buf flush ddd bytes SMTP<< 250 OK 127.0.0.1 in hosts_require_auth? no (option unset) SMTP>> MAIL FROM: -cmd buf flush 37 bytes +cmd buf flush ddd bytes SMTP<< 250 OK SMTP>> RCPT TO: -cmd buf flush 15 bytes +cmd buf flush ddd bytes SMTP<< 451 Temporary error LOG: MAIN H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:: 451 Temporary error added retry item for R:x@y: errno=-44 more_errno=dd,A flags=0 - SMTP>> QUIT -cmd buf flush 6 bytes + SMTP+> QUIT +cmd buf flush ddd bytes (more expected) + SMTP(shutdown)>> + SMTP<< 250 OK SMTP(close)>> -set_process_info: pppp delivering 10HmaX-0005vi-00: just tried 127.0.0.1 [127.0.0.1] for x@y: result OK -address match test: subject=*@127.0.0.1 pattern=* -127.0.0.1 in "*"? yes (matched "*") -*@127.0.0.1 in "*"? yes (matched "*") -checking status of V4NET.0.0.0 -locking TESTSUITE/spool/db/retry.lockfile -locked TESTSUITE/spool/db/retry.lockfile -EXIM_DBOPEN(TESTSUITE/spool/db/retry) -returned from EXIM_DBOPEN +cmdlog: '220:EHLO:250:MAIL:250:RCPT:451:QUIT+:250' +set_process_info: pppp delivering 10HmaX-000000005vi-0000: just tried 127.0.0.1 [127.0.0.1]:PORT_S for x@y: result OK +*@127.0.0.1 in "*"? + list element: * + address match test: subject=*@127.0.0.1 pattern=* + 127.0.0.1 in "*"? + list element: * + 127.0.0.1 in "*"? yes (matched "*") + *@127.0.0.1 in "*"? yes (matched "*") +Clearing TFO as not first host for message +checking retry status of V4NET.0.0.0 + 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 V4NET.0.0.0 in serialize_hosts? no (option unset) -set_process_info: pppp delivering 10HmaX-0005vi-00 to V4NET.0.0.0 [V4NET.0.0.0] (x@y) -Connecting to V4NET.0.0.0 [V4NET.0.0.0]:1224 ... failed: Network Error +set_process_info: pppp delivering 10HmaX-000000005vi-0000 to V4NET.0.0.0 [V4NET.0.0.0]:PORT_S (x@y) +Connecting to V4NET.0.0.0 [V4NET.0.0.0]:PORT_S ... + V4NET.0.0.0 in hosts_try_fastopen? + failed: Network Error LOG: MAIN H=V4NET.0.0.0 [V4NET.0.0.0] Network Error -set_process_info: pppp delivering 10HmaX-0005vi-00: just tried V4NET.0.0.0 [V4NET.0.0.0] for x@y: result DEFER -added retry item for T:V4NET.0.0.0:V4NET.0.0.0:1224: errno=dd more_errno=dd,A flags=2 -set_process_info: pppp delivering 10HmaX-0005vi-00 (just run smtp for x@y in subprocess) +set_process_info: pppp delivering 10HmaX-000000005vi-0000: just tried V4NET.0.0.0 [V4NET.0.0.0]:PORT_S for x@y: result DEFER +added retry item for T:[V4NET.0.0.0]:V4NET.0.0.0:PORT_S: errno=dd more_errno=dd,A flags=2 +set_process_info: pppp delivering 10HmaX-000000005vi-0000: waiting for a remote delivery subprocess to finish +set_process_info: pppp delivering 10HmaX-000000005vi-0000 (just run smtp for x@y in subprocess) +search_tidyup called search_tidyup called -set_process_info: pppp delivering 10HmaX-0005vi-00: waiting for a remote delivery subprocess to finish -set_process_info: pppp delivering 10HmaX-0005vi-00 -reading retry information for T:V4NET.0.0.0:V4NET.0.0.0:1224 from subprocess +>>>>>>>>>>>>>>>> Exim pid=p1235 (transport) terminating with rc=0 >>>>>>>>>>>>>>>> +reading retry information for T:[V4NET.0.0.0]:V4NET.0.0.0:PORT_S from subprocess added retry item reading retry information for R:x@y from subprocess added retry item +set_process_info: pppp delivering 10HmaX-000000005vi-0000 LOG: MAIN == x@y R=r1 T=smtp defer (dd): Network Error H=127.0.0.1 [127.0.0.1] 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=p1234 +set_process_info: pppp tidying up after delivering 10HmaX-000000005vi-0000 Processing retry items Succeeded addresses: Failed addresses: Deferred addresses: -x@y -locking TESTSUITE/spool/db/retry.lockfile -locked TESTSUITE/spool/db/retry.lockfile -EXIM_DBOPEN(TESTSUITE/spool/db/retry) -returned from EXIM_DBOPEN -opened hints database TESTSUITE/spool/db/retry: flags=O_RDWR -address match test: subject=x@y pattern=* -y in "*"? yes (matched "*") -x@y in "*"? yes (matched "*") + x@y + EXIM_DBOPEN: file dir flags=O_RDWR + returned from EXIM_DBOPEN: 0xAAAAAAAA + opened hints database TESTSUITE/spool/db/retry: flags=O_RDWR + x@y in "*"? + list element: * + address match test: subject=x@y pattern=* + y in "*"? + list element: * + y in "*"? yes (matched "*") + x@y in "*"? yes (matched "*") retry for R:x@y = * 0 0 -dbfn_read: key=R:x@y + dbfn_read: key=R:x@y + dbfn_read: null return failing_interval=ttt message_age=ttt Writing retry data for R:x@y first failed=dddd last try=dddd next try=+1 expired=1 errno=-44 more_errno=dd,A H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:: 451 Temporary error -dbfn_write: key=R:x@y -address match test: subject=*@V4NET.0.0.0 pattern=* -V4NET.0.0.0 in "*"? yes (matched "*") -*@V4NET.0.0.0 in "*"? yes (matched "*") -retry for T:V4NET.0.0.0:V4NET.0.0.0:1224 (y) = * 0 0 -dbfn_read: key=T:V4NET.0.0.0:V4NET.0.0.0:1224 + dbfn_write: key=R:x@y datalen 148 + *@V4NET.0.0.0 in "*"? + list element: * + address match test: subject=*@V4NET.0.0.0 pattern=* + V4NET.0.0.0 in "*"? + list element: * + V4NET.0.0.0 in "*"? yes (matched "*") + *@V4NET.0.0.0 in "*"? yes (matched "*") +retry for T:[V4NET.0.0.0]:V4NET.0.0.0:PORT_S (y) = * 0 0 + dbfn_read: key=T:[V4NET.0.0.0]:V4NET.0.0.0:PORT_S + dbfn_read: null return failing_interval=ttt message_age=ttt on queue longer than maximum retry -Writing retry data for T:V4NET.0.0.0:V4NET.0.0.0:1224 +Writing retry data for T:[V4NET.0.0.0]:V4NET.0.0.0:PORT_S first failed=dddd last try=dddd next try=+0 expired=0 errno=dd more_errno=dd,A Network Error -dbfn_write: key=T:V4NET.0.0.0:V4NET.0.0.0:1224 + dbfn_write: key=T:[V4NET.0.0.0]:V4NET.0.0.0:PORT_S datalen 70 timed out: all retries expired LOG: MAIN ** x@y: retry timeout exceeded + EXIM_DBCLOSE(0xAAAAAAAA) + closed hints database end of retry processing -exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xebb95ced -odi -odi -t -oem -oi -f <> -E10HmaX-0005vi-00 +exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xd7715ced -MCd bounce-message -odi -odi -t -oem -oi -f <> -E10HmaX-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 configuration file is TESTSUITE/test-config trusted user admin user changed uid/gid: privilege not needed - uid=EXIM_UID gid=EXIM_GID pid=pppp -DSN: r0 propagating DSN -DSN: r1 propagating DSN + uid=EXIM_UID gid=EXIM_GID pid=p1236 originator: uid=EXIM_UID gid=EXIM_GID login=EXIMUSER name= sender address = set_process_info: pppp accepting a local non-SMTP message from <> +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 Sender: search_tidyup called >>Headers received: @@ -186,77 +212,88 @@ X-Failed-Recipients: x@y Auto-Submitted: auto-replied From: Mail Delivery System To: CALLER@myhost.test.ex +References: Content-Type: multipart/report; report-type=delivery-status; boundary=NNNNNNNNNN-eximdsn-MMMMMMMMMM MIME-Version: 1.0 Subject: Mail delivery failed: returning message to sender -rewrite_one_header: type=F: - From: Mail Delivery System -rewrite_one_header: type=T: - To: CALLER@myhost.test.ex +qualify & rewrite recipients list +rewrite rules on sender address +qualify and rewrite headers + rewrite_one_header: type=F: + From: Mail Delivery System + rewrite_one_header: type=T: + To: CALLER@myhost.test.ex search_tidyup called >>Headers after rewriting and local additions: - X-Failed-Recipients: x@y - Auto-Submitted: auto-replied -F From: Mail Delivery System -T To: CALLER@myhost.test.ex - Content-Type: multipart/report; report-type=delivery-status; boundary=NNNNNNNNNN-eximdsn-MMMMMMMMMM - MIME-Version: 1.0 - Subject: Mail delivery failed: returning message to sender -I Message-Id: - Date: Tue, 2 Mar 1999 09:44:33 +0000 + X-Failed-Recipients: x@y + Auto-Submitted: auto-replied + F From: Mail Delivery System + T To: CALLER@myhost.test.ex + References: + Content-Type: multipart/report; report-type=delivery-status; boundary=NNNNNNNNNN-eximdsn-MMMMMMMMMM + MIME-Version: 1.0 + Subject: Mail delivery failed: returning message to sender + I Message-Id: + Date: Tue, 2 Mar 1999 09:44:33 +0000 -Data file name: TESTSUITE/spool//input//10HmaY-0005vi-00-D -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 EXIMUSER by myhost.test.ex with local (Exim x.yz) - id 10HmaY-0005vi-00 - for CALLER@myhost.test.ex; Tue, 2 Mar 1999 09:44:33 +0000 -calling local_scan(); timeout=300 -local_scan() returned 0 NULL -Writing spool header file: TESTSUITE/spool//input//hdr.pppp -Renaming spool header file: TESTSUITE/spool//input//10HmaY-0005vi-00-H + id 10HmaY-000000005vi-0000 + for CALLER@myhost.test.ex; + Tue, 2 Mar 1999 09:44:33 +0000 +Writing spool header file: TESTSUITE/spool//input//hdr.10HmaY-000000005vi-0000 +Renaming spool header file: TESTSUITE/spool//input//10HmaY-000000005vi-0000-H Size of headers = sss LOG: MAIN - <= <> R=10HmaX-0005vi-00 U=EXIMUSER P=local S=sss + <= <> R=10HmaX-000000005vi-0000 U=EXIMUSER P=local S=sss search_tidyup called -exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xebb95ced -odi -Mc 10HmaY-0005vi-00 +exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xd7715ced -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=p1237 configuration file is TESTSUITE/test-config trusted user admin user -DSN: r0 propagating DSN -DSN: r1 propagating DSN +dropping to exim gid; retaining priv uid set_process_info: pppp delivering specified messages -set_process_info: pppp delivering 10HmaY-0005vi-00 -locking TESTSUITE/spool/db/retry.lockfile -locked TESTSUITE/spool/db/retry.lockfile -EXIM_DBOPEN(TESTSUITE/spool/db/retry) -returned from EXIM_DBOPEN -opened hints database TESTSUITE/spool/db/retry: flags=O_RDONLY +set_process_info: pppp delivering 10HmaY-000000005vi-0000 + EXIM_DBOPEN: file dir flags=O_RDONLY + returned from EXIM_DBOPEN: 0xAAAAAAAA + opened hints database TESTSUITE/spool/db/retry: flags=O_RDONLY >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> Considering: CALLER@myhost.test.ex unique = CALLER@myhost.test.ex -dbfn_read: key=R:myhost.test.ex -dbfn_read: key=R:CALLER@myhost.test.ex -dbfn_read: key=R:CALLER@myhost.test.ex:<> -no domain retry record -no address retry record + dbfn_read: key=R:myhost.test.ex + dbfn_read: null return + dbfn_read: key=R:CALLER@myhost.test.ex + dbfn_read: null return + dbfn_read: key=R:CALLER@myhost.test.ex:<> + dbfn_read: null return +no domain retry record +no address retry record CALLER@myhost.test.ex: queued for routing + EXIM_DBCLOSE(0xAAAAAAAA) + closed hints database >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> routing CALLER@myhost.test.ex --------> r0 router <-------- local_part=CALLER domain=myhost.test.ex checking local_parts -CALLER in "CALLER"? yes (matched "CALLER") +CALLER in local_parts? + list element: CALLER + CALLER in local_parts? yes (matched "CALLER") checking senders -address match test: subject= pattern= - in ":"? yes (matched "") + in senders? + list element: + address match test: subject= pattern= + in senders? yes (matched "") calling r0 router -rda_interpret (string): :blackhole: -expanded: :blackhole: +rda_interpret (string): ':blackhole:' +expanded: ':blackhole:' file is not a filter file parse_forward_list: :blackhole: extract item: :blackhole: @@ -271,11 +308,11 @@ After routing: Deferred addresses: search_tidyup called 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=p1237 +set_process_info: pppp tidying up after delivering 10HmaY-000000005vi-0000 Processing retry items Succeeded addresses: -CALLER@myhost.test.ex: no retry items + CALLER@myhost.test.ex: no retry items Failed addresses: Deferred addresses: end of retry processing @@ -283,14 +320,14 @@ LOG: MAIN Completed search_tidyup called search_tidyup called ->>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>> +>>>>>>>>>>>>>>>> Exim pid=p1237 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>> search_tidyup called ->>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>> -Writing spool header file: TESTSUITE/spool//input//hdr.pppp -Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H +>>>>>>>>>>>>>>>> Exim pid=p1236 (bounce-message) terminating with rc=0 >>>>>>>>>>>>>>>> +Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-000000005vi-0000 +Renaming spool header file: TESTSUITE/spool//input//10HmaX-000000005vi-0000-H Size of headers = sss LOG: MAIN Completed search_tidyup called search_tidyup called ->>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>> +>>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>