Testsuite: use name for nonexistent host that the testsuite forces to not-resolve
[exim.git] / test / stderr / 0551
CommitLineData
fca5cb18
JH
114:07:56 7857 Exim version x.yz uid=CALLER_UID gid=CALLER_GID pid=pppp D=fffdffff
214:07:56 7857 Total 20 lookups
314:07:56 7857 changed uid/gid: forcing real = effective
414:07:56 7857 uid=uuuu gid=CALLER_GID pid=pppp
514:07:56.452 7857 configuration file is TESTSUITE/test-config
614:07:56.452 7857 log selectors = 00000ffc 0c64c60a
714:07:56.452 7857 cwd=TESTSUITE 8 args: TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d+all -DLOG_SELECTOR=+queue_time+queue_time_overall+deliver_time+millisec -odi userx@test.ex
814:07:56.452 7857 admin user
914:07:56.453 7857 changed uid/gid: privilege not needed
1014:07:56.453 7857 uid=EXIM_UID gid=EXIM_GID pid=pppp
1114:07:56.453 7857 DSN: r1 propagating DSN
1214:07:56.453 7857 seeking password data for user "CALLER": cache not available
1314:07:56.453 7857 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
1414:07:56.453 7857 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
1514:07:56.453 7857 sender address = CALLER@myhost.test.ex
1614:07:56.453 7857 set_process_info: pppp accepting a local non-SMTP message from <CALLER@myhost.test.ex>
1714:07:56.453 7857 spool directory space = 157852648K inodes = 26802407 check_space = 10240K inodes = 100 msg_size = 0
1814:07:56.453 7857 log directory space = 157852648K inodes = 26802407 check_space = 10240K inodes = 100
1914:07:56.453 7857 Sender: CALLER@myhost.test.ex
2014:07:56.453 7857 Recipients:
2114:07:56.453 7857 userx@test.ex
2214:07:56.453 7857 search_tidyup called
2314:07:56.453 7857 >>Headers received:
2414:07:56.453 7857
2514:07:56.453 7857 rewrite_one_header: type=F:
2614:07:56.453 7857 From: CALLER_NAME <CALLER@myhost.test.ex>
2714:07:56.453 7857 search_tidyup called
2814:07:56.453 7857 >>Headers after rewriting and local additions:
2914:07:56.453 7857 I Message-Id: <E10HmaZ-0005vi-00@myhost.test.ex>
3014:07:56.453 7857 F From: CALLER_NAME <CALLER@myhost.test.ex>
3114:07:56.453 7857 Date: Tue, 2 Mar 1999 09:44:33 +0000
3214:07:56.453 7857
3314:07:56.453 7857 Data file name: TESTSUITE/spool//input//10HmaZ-0005vi-00-D
3414:07:56.455 7857 Data file written for message 10HmaZ-0005vi-00
3514:07:56.455 7857 ┌considering: ${tod_full}
3614:07:56.455 7857 ├──expanding: ${tod_full}
3714:07:56.455 7857 └─────result: Tue, 2 Mar 1999 09:44:33 +0000
3814:07:56.455 7857 ┌considering: Received: ${if def:sender_rcvhost {from $sender_rcvhost
3914:07:56.455 7857 }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
4014:07:56.455 7857 }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol14:07:56.455 7857}} (Exim $version_number)
4114:07:56.455 7857 ${if def:sender_address {(envelope-from <$sender_address>)
4214:07:56.455 7857 }}id $message_exim_id${if def:received_for {
4314:07:56.455 7857 for $received_for}}
4414:07:56.455 7857 ├──condition: def:sender_rcvhost
4514:07:56.455 7857 ├─────result: false
4614:07:56.455 7857 ┌───scanning: from $sender_rcvhost
4714:07:56.455 7857 }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
4814:07:56.455 7857 }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol14:07:56.455 7857}} (Exim $version_number)
4914:07:56.455 7857 ${if def:sender_address {(envelope-from <$sender_address>)
5014:07:56.455 7857 }}id $message_exim_id${if def:received_for {
5114:07:56.455 7857 for $received_for}}
5214:07:56.455 7857 ├──expanding: from $sender_rcvhost
5314:07:56.455 7857
5414:07:56.455 7857 ├─────result: from
5514:07:56.455 7857
5614:07:56.455 7857 └───skipping: result is not used
5714:07:56.455 7857 ┌considering: ${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
5814:07:56.455 7857 }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol14:07:56.455 7857}} (Exim $version_number)
5914:07:56.455 7857 ${if def:sender_address {(envelope-from <$sender_address>)
6014:07:56.455 7857 }}id $message_exim_id${if def:received_for {
6114:07:56.455 7857 for $received_for}}
6214:07:56.455 7857 ├──condition: def:sender_ident
6314:07:56.455 7857 ├─────result: true
6414:07:56.455 7857 ┌considering: from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
6514:07:56.455 7857 }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol14:07:56.455 7857}} (Exim $version_number)
6614:07:56.455 7857 ${if def:sender_address {(envelope-from <$sender_address>)
6714:07:56.455 7857 }}id $message_exim_id${if def:received_for {
6814:07:56.455 7857 for $received_for}}
6914:07:56.455 7857 ╎┌considering: $sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
7014:07:56.455 7857 ╎ }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol14:07:56.455 7857 ╎}} (Exim $version_number)
7114:07:56.455 7857 ╎ ${if def:sender_address {(envelope-from <$sender_address>)
7214:07:56.455 7857 ╎ }}id $message_exim_id${if def:received_for {
7314:07:56.455 7857 ╎ for $received_for}}
7414:07:56.455 7857 ╎├──expanding: $sender_ident
7514:07:56.455 7857 ╎└─────result: CALLER
7614:07:56.455 7857 ├──expanding: from ${quote_local_part:$sender_ident}
7714:07:56.455 7857 └─────result: from CALLER
7814:07:56.455 7857 ├──condition: def:sender_helo_name
7914:07:56.455 7857 ├─────result: false
8014:07:56.455 7857 ┌───scanning: (helo=$sender_helo_name)
8114:07:56.455 7857 }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol14:07:56.455 7857}} (Exim $version_number)
8214:07:56.455 7857 ${if def:sender_address {(envelope-from <$sender_address>)
8314:07:56.455 7857 }}id $message_exim_id${if def:received_for {
8414:07:56.455 7857 for $received_for}}
8514:07:56.455 7857 ├──expanding: (helo=$sender_helo_name)
8614:07:56.455 7857
8714:07:56.455 7857 ├─────result: (helo=)
8814:07:56.455 7857
8914:07:56.455 7857 └───skipping: result is not used
9014:07:56.455 7857 ├──expanding: ${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
9114:07:56.455 7857 }}
9214:07:56.455 7857 └─────result: from CALLER
9314:07:56.455 7857 ├──condition: def:received_protocol
9414:07:56.455 7857 ├─────result: true
9514:07:56.455 7857 ┌considering: with $received_protocol14:07:56.455 7857}} (Exim $version_number)
9614:07:56.455 7857 ${if def:sender_address {(envelope-from <$sender_address>)
9714:07:56.455 7857 }}id $message_exim_id${if def:received_for {
9814:07:56.455 7857 for $received_for}}
9914:07:56.455 7857 ├──expanding: with $received_protocol
10014:07:56.455 7857 └─────result: with local
10114:07:56.455 7857 ├──condition: def:tls_cipher
10214:07:56.455 7857 ├─────result: false
10314:07:56.455 7857 ┌───scanning: ($tls_cipher)
10414:07:56.455 7857 }}(Exim $version_number)
10514:07:56.455 7857 ${if def:sender_address {(envelope-from <$sender_address>)
10614:07:56.455 7857 }}id $message_exim_id${if def:received_for {
10714:07:56.455 7857 for $received_for}}
10814:07:56.455 7857 ├──expanding: ($tls_cipher)
10914:07:56.455 7857
11014:07:56.455 7857 ├─────result: ()
11114:07:56.455 7857
11214:07:56.455 7857 └───skipping: result is not used
11314:07:56.455 7857 ├──condition: def:sender_address
11414:07:56.455 7857 ├─────result: true
11514:07:56.455 7857 ┌considering: (envelope-from <$sender_address>)
11614:07:56.455 7857 }}id $message_exim_id${if def:received_for {
11714:07:56.455 7857 for $received_for}}
11814:07:56.455 7857 ├──expanding: (envelope-from <$sender_address>)
11914:07:56.455 7857
12014:07:56.455 7857 └─────result: (envelope-from <CALLER@myhost.test.ex>)
12114:07:56.455 7857
12214:07:56.455 7857 ├──condition: def:received_for
12314:07:56.455 7857 ├─────result: true
12414:07:56.455 7857 ┌considering:
12514:07:56.455 7857 for $received_for}}
12614:07:56.455 7857 ├──expanding:
12714:07:56.455 7857 for $received_for
12814:07:56.455 7857 └─────result:
12914:07:56.455 7857 for userx@test.ex
13014:07:56.455 7857 ├──expanding: Received: ${if def:sender_rcvhost {from $sender_rcvhost
13114:07:56.455 7857 }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
13214:07:56.455 7857 }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol14:07:56.455 7857}} (Exim $version_number)
13314:07:56.455 7857 ${if def:sender_address {(envelope-from <$sender_address>)
13414:07:56.455 7857 }}id $message_exim_id${if def:received_for {
13514:07:56.455 7857 for $received_for}}
13614:07:56.455 7857 └─────result: Received: from CALLER by myhost.test.ex with local (Exim x.yz)
13714:07:56.455 7857 (envelope-from <CALLER@myhost.test.ex>)
13814:07:56.455 7857 id 10HmaZ-0005vi-00
13914:07:56.455 7857 for userx@test.ex
14014:07:56.455 7857 >>Generated Received: header line
14114:07:56.455 7857 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
14214:07:56.455 7857 (envelope-from <CALLER@myhost.test.ex>)
14314:07:56.455 7857 id 10HmaZ-0005vi-00
14414:07:56.455 7857 for userx@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
14514:07:56.455 7857 calling local_scan(); timeout=300
14614:07:56.455 7857 local_scan() returned 0 NULL
14714:07:56.455 7857 ┌considering: ${tod_full}
14814:07:56.455 7857 ├──expanding: ${tod_full}
14914:07:56.455 7857 └─────result: Tue, 2 Mar 1999 09:44:33 +0000
15014:07:56.455 7857 Writing spool header file: TESTSUITE/spool//input//hdr.7857
15114:07:56.455 7857 DSN: Write SPOOL :-dsn_envid NULL
15214:07:56.455 7857 DSN: Write SPOOL :-dsn_ret 0
15314:07:56.455 7857 DSN: Flags :0
15414:07:56.455 7857 DSN: **** SPOOL_OUT - address: |userx@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
15514:07:56.457 7857 Renaming spool header file: TESTSUITE/spool//input//10HmaZ-0005vi-00-H
15614:07:56.459 7857 Size of headers = sss
15714:07:56.459 7857 LOG: MAIN
15814:07:56.459 7857 <= CALLER@myhost.test.ex U=CALLER P=local S=sss
15914:07:56.459 7857 search_tidyup called
16014:07:56.459 7858 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -DLOG_SELECTOR=+queue_time+queue_time_overall+deliver_time+millisec -C TESTSUITE/test-config -d=0xfffdffff -odi -Mc 10HmaZ-0005vi-00
16114:07:56 7858 Exim version x.yz uid=EXIM_UID gid=EXIM_GID pid=pppp D=fffdffff
16214:07:56 7858 Total 20 lookups
16314:07:56 7858 changed uid/gid: forcing real = effective
16414:07:56 7858 uid=uuuu gid=EXIM_GID pid=pppp
16514:07:56.565 7858 configuration file is TESTSUITE/test-config
16614:07:56.565 7858 log selectors = 00000ffc 0c64c60a
16714:07:56.565 7858 cwd=TESTSUITE/spool 9 args: TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -DLOG_SELECTOR=+queue_time+queue_time_overall+deliver_time+millisec -C TESTSUITE/test-config -d=0xfffdffff -odi -Mc 10HmaZ-0005vi-00
16814:07:56.565 7858 trusted user
16914:07:56.565 7858 admin user
17014:07:56.565 7858 DSN: r1 propagating DSN
17114:07:56.565 7858 seeking password data for user "CALLER": cache not available
17214:07:56.565 7858 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
17314:07:56.565 7858 set_process_info: pppp delivering specified messages
17414:07:56.565 7858 set_process_info: pppp delivering 10HmaZ-0005vi-00
17514:07:56.565 7858 Trying spool file TESTSUITE/spool//input//10HmaZ-0005vi-00-D
17614:07:56.565 7858 reading spool file 10HmaZ-0005vi-00-H
17714:07:56.565 7858 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@myhost.test.ex
17814:07:56.565 7858 sender_local=1 ident=CALLER
17914:07:56.565 7858 Non-recipients:
18014:07:56.565 7858 Empty Tree
18114:07:56.565 7858 ---- End of tree ----
18214:07:56.565 7858 recipients_count=1
18314:07:56.565 7858 **** SPOOL_IN - No additional fields
18414:07:56.565 7858 body_linecount=1 message_linecount=7
18514:07:56.565 7858 DSN: set orcpt: NULL flags: 0
18614:07:56.565 7858 Delivery address list:
18714:07:56.565 7858 userx@test.ex
18814:07:56.566 7858 locking TESTSUITE/spool/db/retry.lockfile
18914:07:56.566 7858 locked TESTSUITE/spool/db/retry.lockfile
19014:07:56.566 7858 EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags 0x0
19114:07:56.566 7858 returned from EXIM_DBOPEN: (nil)
19214:07:56.566 7858 failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
19314:07:56.566 7858 no retry data available
19414:07:56.566 7858 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
19514:07:56.566 7858 Considering: userx@test.ex
19614:07:56.566 7858 unique = userx@test.ex
19714:07:56.566 7858 no domain retry record
19814:07:56.566 7858 no address retry record
19914:07:56.566 7858 userx@test.ex: queued for routing
20014:07:56.566 7858 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
20114:07:56.566 7858 routing userx@test.ex
20214:07:56.566 7858 --------> r1 router <--------
20314:07:56.566 7858 local_part=userx domain=test.ex
20414:07:56.566 7858 calling r1 router
20514:07:56.566 7858 r1 router called for userx@test.ex
20614:07:56.566 7858 domain = test.ex
20714:07:56.566 7858 set transport t1
20814:07:56.566 7858 queued for t1 transport: local_part = userx
20914:07:56.566 7858 domain = test.ex
21014:07:56.566 7858 errors_to=NULL
21114:07:56.566 7858 domain_data=NULL localpart_data=NULL
21214:07:56.566 7858 routed by r1 router
21314:07:56.566 7858 envelope to: userx@test.ex
21414:07:56.566 7858 transport: t1
21514:07:56.566 7858 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
21614:07:56.566 7858 After routing:
21714:07:56.566 7858 Local deliveries:
21814:07:56.566 7858 userx@test.ex
21914:07:56.566 7858 Remote deliveries:
22014:07:56.566 7858 Failed addresses:
22114:07:56.566 7858 Deferred addresses:
22214:07:56.566 7858 search_tidyup called
22314:07:56.566 7858 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
22414:07:56.566 7858 --------> userx@test.ex <--------
22514:07:56.566 7858 locking TESTSUITE/spool/db/retry.lockfile
22614:07:56.566 7858 locked TESTSUITE/spool/db/retry.lockfile
22714:07:56.566 7858 EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags 0x0
22814:07:56.566 7858 returned from EXIM_DBOPEN: (nil)
22914:07:56.566 7858 failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
23014:07:56.566 7858 no retry data available
23114:07:56.566 7858 search_tidyup called
23214:07:56.566 7859 changed uid/gid: local delivery to userx <userx@test.ex> transport=t1
23314:07:56.566 7859 uid=CALLER_UID gid=CALLER_GID pid=pppp
23414:07:56.566 7859 home=NULL current=/
23514:07:56.566 7859 set_process_info: pppp delivering 10HmaZ-0005vi-00 to userx using t1
23614:07:56.566 7859 appendfile transport entered
23714:07:56.566 7859 ┌considering: TESTSUITE/test-mail/$local_part
23814:07:56.566 7859 ├──expanding: TESTSUITE/test-mail/$local_part
23914:07:56.566 7859 └─────result: TESTSUITE/test-mail/userx
24014:07:56.566 7859 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
24114:07:56.566 7859 file=TESTSUITE/test-mail/userx format=unix
24214:07:56.566 7859 message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
24314:07:56.566 7859 message_suffix=\n
24414:07:56.566 7859 maildir_use_size_file=no
24514:07:56.566 7859 locking by lockfile fcntl
24614:07:56.566 7859 lock name: TESTSUITE/test-mail/userx.lock
24714:07:56.566 7859 hitch name: TESTSUITE/test-mail/userx.lock.test.ex.dddddddd.pppppppp
24814:07:56.566 7859 lock file created
24914:07:56.566 7859 mailbox TESTSUITE/test-mail/userx is locked
25014:07:56.566 7859 writing to file TESTSUITE/test-mail/userx
25114:07:56.566 7859 ┌considering: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
25214:07:56.566 7859
25314:07:56.566 7859 ├──condition: def:return_path
25414:07:56.566 7859 ├─────result: true
25514:07:56.566 7859 ┌considering: $return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
25614:07:56.566 7859
25714:07:56.566 7859 ├──expanding: $return_path
25814:07:56.566 7859 └─────result: CALLER@myhost.test.ex
25914:07:56.566 7859 ┌───scanning: MAILER-DAEMON}} ${tod_bsdinbox}
26014:07:56.566 7859
26114:07:56.566 7859 ├──expanding: MAILER-DAEMON
26214:07:56.566 7859 ├─────result: MAILER-DAEMON
26314:07:56.566 7859 └───skipping: result is not used
26414:07:56.566 7859 ├──expanding: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
26514:07:56.566 7859
26614:07:56.566 7859 └─────result: From CALLER@myhost.test.ex Tue Mar 02 09:44:33 1999
26714:07:56.566 7859
26814:07:56.566 7859 writing data block fd=6 size=sss timeout=0
26914:07:56.567 7859 cannot use sendfile for body: spoolfile not wireformat
27014:07:56.567 7859 writing data block fd=6 size=sss timeout=0
27114:07:56.567 7859 writing data block fd=6 size=sss timeout=0
27214:07:56.568 7859 appendfile yields 0 with errno=dd more_errno=dd
27314:07:56.568 7859 search_tidyup called
27414:07:56.868 7858 journalling userx@test.ex
27514:07:56.871 7858 t1 transport returned OK for userx@test.ex
27614:07:56.871 7858 post-process userx@test.ex (0)
27714:07:56.871 7858 userx@test.ex delivered
27814:07:56.871 7858 LOG: MAIN
27914:07:56.871 7858 => userx <userx@test.ex> R=r1 T=t1 QT=q.qqqs DT=q.qqqs
28014:07:56.871 7858 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
28114:07:56.871 7858 changed uid/gid: post-delivery tidying
28214:07:56.871 7858 uid=EXIM_UID gid=EXIM_GID pid=pppp
28314:07:56.871 7858 set_process_info: pppp tidying up after delivering 10HmaZ-0005vi-00
28414:07:56.871 7858 Processing retry items
28514:07:56.871 7858 Succeeded addresses:
28614:07:56.871 7858 userx@test.ex: no retry items
28714:07:56.871 7858 Failed addresses:
28814:07:56.871 7858 Deferred addresses:
28914:07:56.871 7858 end of retry processing
29014:07:56.871 7858 DSN: processing router : r1
29114:07:56.871 7858 DSN: processing successful delivery address: userx@test.ex
29214:07:56.871 7858 DSN: Sender_address: CALLER@myhost.test.ex
29314:07:56.871 7858 DSN: orcpt: NULL flags: 0
29414:07:56.871 7858 DSN: envid: NULL ret: 0
29514:07:56.871 7858 DSN: Final recipient: userx@test.ex
29614:07:56.871 7858 DSN: Remote SMTP server supports DSN: 0
29714:07:56.871 7858 DSN: not sending DSN success message
29814:07:56.871 7858 LOG: MAIN
29914:07:56.871 7858 Completed QT=q.qqqs
30014:07:56.871 7858 end delivery of 10HmaZ-0005vi-00
30114:07:56.871 7858 search_tidyup called
30214:07:56.871 7858 search_tidyup called
30314:07:56.871 7858 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
30414:07:56.871 7857 search_tidyup called
30514:07:56.871 7857 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>