Logging: make cipher info available for continued-TLS connection deliveries
[exim.git] / test / stderr / 0402
1 Exim version x.yz ....
2 changed uid/gid: forcing real = effective
3   uid=uuuu gid=CALLER_GID pid=pppp
4 configuration file is TESTSUITE/test-config
5 admin user
6 changed uid/gid: privilege not needed
7   uid=EXIM_UID gid=EXIM_GID pid=pppp
8 DSN: r1 propagating DSN
9 DSN: r2 propagating DSN
10 DSN: r3 propagating DSN
11 DSN: r4 propagating DSN
12 DSN: r5 propagating DSN
13 seeking password data for user "CALLER": cache not available
14 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
15 seeking password data for user "CALLER": using cached result
16 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
17 seeking password data for user "CALLER": using cached result
18 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
19 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
20 sender address = CALLER@test.ex
21 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
22 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
23 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
24 Sender: CALLER@test.ex
25 Recipients:
26   CALLER@test.ex
27   usery@test.ex
28   userz
29   rd+CALLER
30   rd+usery
31 search_tidyup called
32 >>Headers received:
33
34 rewrite_one_header: type=F:
35   From: CALLER_NAME <CALLER@test.ex>
36 search_tidyup called
37 >>Headers after rewriting and local additions:
38 I Message-Id: <E10HmaX-0005vi-00@mail.test.ex>
39 F From: CALLER_NAME <CALLER@test.ex>
40   Date: Tue, 2 Mar 1999 09:44:33 +0000
41
42 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
43 Data file written for message 10HmaX-0005vi-00
44  ┌considering: ${tod_full}
45  ├──expanding: ${tod_full}
46  └─────result: Tue, 2 Mar 1999 09:44:33 +0000
47  ┌considering: Received: ${if def:sender_rcvhost {from $sender_rcvhost
48         }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
49         }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol}} (Exim $version_number)
50         ${if def:sender_address {(envelope-from <$sender_address>)
51         }}id $message_exim_id${if def:received_for {
52         for $received_for}}
53  ├──condition: def:sender_rcvhost
54  ├─────result: false
55   ┌───scanning: from $sender_rcvhost
56         }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
57         }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol}} (Exim $version_number)
58         ${if def:sender_address {(envelope-from <$sender_address>)
59         }}id $message_exim_id${if def:received_for {
60         for $received_for}}
61   ├──expanding: from $sender_rcvhost
62         
63   ├─────result: from 
64         
65   └───skipping: result is not used
66   ┌considering: ${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
67         }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol}} (Exim $version_number)
68         ${if def:sender_address {(envelope-from <$sender_address>)
69         }}id $message_exim_id${if def:received_for {
70         for $received_for}}
71   ├──condition: def:sender_ident
72   ├─────result: true
73    ┌considering: from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
74         }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol}} (Exim $version_number)
75         ${if def:sender_address {(envelope-from <$sender_address>)
76         }}id $message_exim_id${if def:received_for {
77         for $received_for}}
78    ╎┌considering: $sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
79    ╎  }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol   ╎}} (Exim $version_number)
80    ╎  ${if def:sender_address {(envelope-from <$sender_address>)
81    ╎  }}id $message_exim_id${if def:received_for {
82    ╎  for $received_for}}
83    ╎├──expanding: $sender_ident
84    ╎└─────result: CALLER
85    ├──expanding: from ${quote_local_part:$sender_ident} 
86    └─────result: from CALLER 
87   ├──condition: def:sender_helo_name
88   ├─────result: false
89    ┌───scanning: (helo=$sender_helo_name)
90         }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol}} (Exim $version_number)
91         ${if def:sender_address {(envelope-from <$sender_address>)
92         }}id $message_exim_id${if def:received_for {
93         for $received_for}}
94    ├──expanding: (helo=$sender_helo_name)
95         
96    ├─────result: (helo=)
97         
98    └───skipping: result is not used
99   ├──expanding: ${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
100         }}
101   └─────result: from CALLER 
102  ├──condition: def:received_protocol
103  ├─────result: true
104   ┌considering: with $received_protocol}} (Exim $version_number)
105         ${if def:sender_address {(envelope-from <$sender_address>)
106         }}id $message_exim_id${if def:received_for {
107         for $received_for}}
108   ├──expanding: with $received_protocol
109   └─────result: with local
110  ├──condition: def:tls_cipher
111  ├─────result: false
112   ┌───scanning: ($tls_cipher)
113         }}(Exim $version_number)
114         ${if def:sender_address {(envelope-from <$sender_address>)
115         }}id $message_exim_id${if def:received_for {
116         for $received_for}}
117   ├──expanding: ($tls_cipher)
118         
119   ├─────result: ()
120         
121   └───skipping: result is not used
122  ├──condition: def:sender_address
123  ├─────result: true
124   ┌considering: (envelope-from <$sender_address>)
125         }}id $message_exim_id${if def:received_for {
126         for $received_for}}
127   ├──expanding: (envelope-from <$sender_address>)
128         
129   └─────result: (envelope-from <CALLER@test.ex>)
130         
131  ├──condition: def:received_for
132  ├─────result: false
133   ┌───scanning: 
134         for $received_for}}
135   ├──expanding: 
136         for $received_for
137   ├─────result: 
138         for 
139   └───skipping: result is not used
140  ├──expanding: Received: ${if def:sender_rcvhost {from $sender_rcvhost
141         }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
142         }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol}} (Exim $version_number)
143         ${if def:sender_address {(envelope-from <$sender_address>)
144         }}id $message_exim_id${if def:received_for {
145         for $received_for}}
146  └─────result: Received: from CALLER by mail.test.ex with local (Exim x.yz)
147         (envelope-from <CALLER@test.ex>)
148         id 10HmaX-0005vi-00
149 >>Generated Received: header line
150 P Received: from CALLER by mail.test.ex with local (Exim x.yz)
151         (envelope-from <CALLER@test.ex>)
152         id 10HmaX-0005vi-00; Tue, 2 Mar 1999 09:44:33 +0000
153 calling local_scan(); timeout=300
154 local_scan() returned 0 NULL
155  ┌considering: ${tod_full}
156  ├──expanding: ${tod_full}
157  └─────result: Tue, 2 Mar 1999 09:44:33 +0000
158 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
159 DSN: Write SPOOL :-dsn_envid NULL
160 DSN: Write SPOOL :-dsn_ret 0
161 DSN: Flags :0
162 DSN: **** SPOOL_OUT - address: |CALLER@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
163 DSN: Flags :0
164 DSN: **** SPOOL_OUT - address: |usery@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
165 DSN: Flags :0
166 DSN: **** SPOOL_OUT - address: |userz@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
167 DSN: Flags :0
168 DSN: **** SPOOL_OUT - address: |rd+CALLER@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
169 DSN: Flags :0
170 DSN: **** SPOOL_OUT - address: |rd+usery@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
171 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
172 Size of headers = sss
173 LOG: MAIN
174   <= CALLER@test.ex U=CALLER P=local S=sss
175 created log directory TESTSUITE/spool/log
176 search_tidyup called
177 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95dfd -odi -Mc 10HmaX-0005vi-00
178 Exim version x.yz ....
179 changed uid/gid: forcing real = effective
180   uid=uuuu gid=EXIM_GID pid=pppp
181 configuration file is TESTSUITE/test-config
182 trusted user
183 admin user
184 DSN: r1 propagating DSN
185 DSN: r2 propagating DSN
186 DSN: r3 propagating DSN
187 DSN: r4 propagating DSN
188 DSN: r5 propagating DSN
189 seeking password data for user "CALLER": cache not available
190 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
191 seeking password data for user "CALLER": using cached result
192 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
193 seeking password data for user "CALLER": using cached result
194 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
195 set_process_info: pppp delivering specified messages
196 set_process_info: pppp delivering 10HmaX-0005vi-00
197 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
198 reading spool file 10HmaX-0005vi-00-H
199 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
200 sender_local=1 ident=CALLER
201 Non-recipients:
202 Empty Tree
203 ---- End of tree ----
204 recipients_count=5
205 **** SPOOL_IN - No additional fields
206 **** SPOOL_IN - No additional fields
207 **** SPOOL_IN - No additional fields
208 **** SPOOL_IN - No additional fields
209 **** SPOOL_IN - No additional fields
210 body_linecount=0 message_linecount=6
211 DSN: set orcpt: NULL  flags: 0
212 DSN: set orcpt: NULL  flags: 0
213 DSN: set orcpt: NULL  flags: 0
214 DSN: set orcpt: NULL  flags: 0
215 DSN: set orcpt: NULL  flags: 0
216 Delivery address list:
217   CALLER@test.ex 
218   usery@test.ex 
219   userz@test.ex 
220   rd+CALLER@test.ex 
221   rd+usery@test.ex 
222 locking TESTSUITE/spool/db/retry.lockfile
223 locked  TESTSUITE/spool/db/retry.lockfile
224 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
225 returned from EXIM_DBOPEN
226 ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
227 no retry data available
228 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
229 Considering: CALLER@test.ex
230 unique = CALLER@test.ex
231 no domain retry record
232 no address retry record
233 CALLER@test.ex: queued for routing
234 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
235 Considering: usery@test.ex
236 unique = usery@test.ex
237 no domain retry record
238 no address retry record
239 usery@test.ex: queued for routing
240 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
241 Considering: userz@test.ex
242 unique = userz@test.ex
243 no domain retry record
244 no address retry record
245 userz@test.ex: queued for routing
246 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
247 Considering: rd+CALLER@test.ex
248 unique = rd+CALLER@test.ex
249 no domain retry record
250 no address retry record
251 rd+CALLER@test.ex: queued for routing
252 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
253 Considering: rd+usery@test.ex
254 unique = rd+usery@test.ex
255 no domain retry record
256 no address retry record
257 rd+usery@test.ex: queued for routing
258 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
259 routing rd+usery@test.ex
260 --------> r1 router <--------
261 local_part=rd+usery domain=test.ex
262 checking local_parts
263 rd+usery in "CALLER"? no (end of list)
264 r1 router skipped: local_parts mismatch
265 --------> r2 router <--------
266 local_part=rd+usery domain=test.ex
267 checking local_parts
268 rd+usery in "usery"? no (end of list)
269 r2 router skipped: local_parts mismatch
270 --------> r3 router <--------
271 local_part=rd+usery domain=test.ex
272 checking local_parts
273 rd+usery in "userz"? no (end of list)
274 r3 router skipped: local_parts mismatch
275 --------> r4 router <--------
276 local_part=rd+usery domain=test.ex
277 stripped prefix rd+
278 checking local_parts
279 usery in "CALLER"? no (end of list)
280 r4 router skipped: local_parts mismatch
281 --------> r5 router <--------
282 local_part=rd+usery domain=test.ex
283 stripped prefix rd+
284 checking local_parts
285 usery in "usery"? yes (matched "usery")
286  ┌considering: /non-exist/$domain
287  ├──expanding: /non-exist/$domain
288  └─────result: /non-exist/test.ex
289 calling r5 router
290 rda_interpret (string): TESTSUITE/test-mail/junk
291 expanded: TESTSUITE/test-mail/junk
292 file is not a filter file
293 parse_forward_list: TESTSUITE/test-mail/junk
294 extract item: TESTSUITE/test-mail/junk
295 set transport ft1
296 r5 router generated TESTSUITE/test-mail/junk
297   pipe, file, or autoreply
298   errors_to=NULL transport=ft1
299   uid=unset gid=unset home=/non-exist/$local_part
300 routed by r5 router
301   envelope to: rd+usery@test.ex
302   transport: <none>
303 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
304 routing rd+CALLER@test.ex
305 --------> r1 router <--------
306 local_part=rd+CALLER domain=test.ex
307 checking local_parts
308 rd+CALLER in "CALLER"? no (end of list)
309 r1 router skipped: local_parts mismatch
310 --------> r2 router <--------
311 local_part=rd+CALLER domain=test.ex
312 checking local_parts
313 rd+CALLER in "usery"? no (end of list)
314 r2 router skipped: local_parts mismatch
315 --------> r3 router <--------
316 local_part=rd+CALLER domain=test.ex
317 checking local_parts
318 rd+CALLER in "userz"? no (end of list)
319 r3 router skipped: local_parts mismatch
320 --------> r4 router <--------
321 local_part=rd+CALLER domain=test.ex
322 stripped prefix rd+
323 checking local_parts
324 CALLER in "CALLER"? yes (matched "CALLER")
325  ┌considering: /non-exist/$local_part
326  ├──expanding: /non-exist/$local_part
327  └─────result: /non-exist/CALLER
328 calling r4 router
329 rda_interpret (string): TESTSUITE/test-mail/junk
330 expanded: TESTSUITE/test-mail/junk
331 file is not a filter file
332 parse_forward_list: TESTSUITE/test-mail/junk
333 extract item: TESTSUITE/test-mail/junk
334 set transport ft1
335 r4 router generated TESTSUITE/test-mail/junk
336   pipe, file, or autoreply
337   errors_to=NULL transport=ft1
338   uid=unset gid=unset home=/non-exist/CALLER
339 routed by r4 router
340   envelope to: rd+CALLER@test.ex
341   transport: <none>
342 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
343 routing userz@test.ex
344 --------> r1 router <--------
345 local_part=userz domain=test.ex
346 checking local_parts
347 userz in "CALLER"? no (end of list)
348 r1 router skipped: local_parts mismatch
349 --------> r2 router <--------
350 local_part=userz domain=test.ex
351 checking local_parts
352 userz in "usery"? no (end of list)
353 r2 router skipped: local_parts mismatch
354 --------> r3 router <--------
355 local_part=userz domain=test.ex
356 checking local_parts
357 userz in "userz"? yes (matched "userz")
358  ┌considering: /non-exist/$domain
359  ├──expanding: /non-exist/$domain
360  └─────result: /non-exist/test.ex
361 calling r3 router
362 r3 router called for userz@test.ex
363   domain = test.ex
364 set transport t2
365 queued for t2 transport: local_part = userz
366 domain = test.ex
367   errors_to=NULL
368   domain_data=NULL localpart_data=NULL
369 routed by r3 router
370   envelope to: userz@test.ex
371   transport: t2
372 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
373 routing usery@test.ex
374 --------> r1 router <--------
375 local_part=usery domain=test.ex
376 checking local_parts
377 usery in "CALLER"? no (end of list)
378 r1 router skipped: local_parts mismatch
379 --------> r2 router <--------
380 local_part=usery domain=test.ex
381 checking local_parts
382 usery in "usery"? yes (matched "usery")
383  ┌considering: /non-exist/$domain
384  ├──expanding: /non-exist/$domain
385  └─────result: /non-exist/test.ex
386 calling r2 router
387 r2 router called for usery@test.ex
388   domain = test.ex
389 set transport t1
390 queued for t1 transport: local_part = usery
391 domain = test.ex
392   errors_to=NULL
393   domain_data=NULL localpart_data=NULL
394 routed by r2 router
395   envelope to: usery@test.ex
396   transport: t1
397 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
398 routing CALLER@test.ex
399 --------> r1 router <--------
400 local_part=CALLER domain=test.ex
401 checking local_parts
402 CALLER in "CALLER"? yes (matched "CALLER")
403  ┌considering: /non-exist/$local_part
404  ├──expanding: /non-exist/$local_part
405  └─────result: /non-exist/CALLER
406 calling r1 router
407 r1 router called for CALLER@test.ex
408   domain = test.ex
409 set transport t1
410 queued for t1 transport: local_part = CALLER
411 domain = test.ex
412   errors_to=NULL
413   domain_data=NULL localpart_data=NULL
414 routed by r1 router
415   envelope to: CALLER@test.ex
416   transport: t1
417 locking TESTSUITE/spool/db/retry.lockfile
418 locked  TESTSUITE/spool/db/retry.lockfile
419 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
420 returned from EXIM_DBOPEN
421 no retry data available
422 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
423 Considering: TESTSUITE/test-mail/junk
424 unique = TESTSUITE/test-mail/junk:rd+CALLER@test.ex
425 queued for ft1 transport
426 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
427 Considering: TESTSUITE/test-mail/junk
428 unique = TESTSUITE/test-mail/junk:rd+usery@test.ex
429 queued for ft1 transport
430 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
431 After routing:
432   Local deliveries:
433     TESTSUITE/test-mail/junk
434     TESTSUITE/test-mail/junk
435     CALLER@test.ex
436     usery@test.ex
437     userz@test.ex
438   Remote deliveries:
439   Failed addresses:
440   Deferred addresses:
441 search_tidyup called
442 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
443 --------> TESTSUITE/test-mail/junk <--------
444 locking TESTSUITE/spool/db/retry.lockfile
445 locked  TESTSUITE/spool/db/retry.lockfile
446 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
447 returned from EXIM_DBOPEN
448 no retry data available
449  ┌considering: /non-exist/$local_part
450  ├──expanding: /non-exist/$local_part
451  └─────result: /non-exist/usery
452 search_tidyup called
453 changed uid/gid: local delivery to TESTSUITE/test-mail/junk <TESTSUITE/test-mail/junk> transport=ft1
454   uid=CALLER_UID gid=CALLER_GID pid=pppp
455   home=/non-exist/usery current=/
456 set_process_info: pppp delivering 10HmaX-0005vi-00 to TESTSUITE/test-mail/junk using ft1
457 appendfile transport entered
458  ┌considering: $address_file
459  ├──expanding: $address_file
460  └─────result: TESTSUITE/test-mail/junk
461 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
462   file=TESTSUITE/test-mail/junk format=unix
463   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
464   message_suffix=\n
465   maildir_use_size_file=no
466   locking by lockfile fcntl 
467 lock name: TESTSUITE/test-mail/junk.lock
468 hitch name: TESTSUITE/test-mail/junk.lock.test.ex.dddddddd.pppppppp
469 lock file created
470 mailbox TESTSUITE/test-mail/junk is locked
471 writing to file TESTSUITE/test-mail/junk
472  ┌considering: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
473  
474  ├──condition: def:return_path
475  ├─────result: true
476   ┌considering: $return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
477   
478   ├──expanding: $return_path
479   └─────result: CALLER@test.ex
480   ┌───scanning: MAILER-DAEMON}} ${tod_bsdinbox}
481   
482   ├──expanding: MAILER-DAEMON
483   ├─────result: MAILER-DAEMON
484   └───skipping: result is not used
485  ├──expanding: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
486  
487  └─────result: From CALLER@test.ex Tue Mar 02 09:44:33 1999
488  
489 writing data block fd=dddd size=sss timeout=0
490 writing data block fd=dddd size=sss timeout=0
491 writing data block fd=dddd size=sss timeout=0
492 appendfile yields 0 with errno=dd more_errno=dd
493 search_tidyup called
494 journalling TESTSUITE/test-mail/junk:rd+usery@test.ex
495 ft1 transport returned OK for TESTSUITE/test-mail/junk
496 post-process TESTSUITE/test-mail/junk (0)
497 TESTSUITE/test-mail/junk delivered
498 rd+usery@test.ex: children all complete
499 LOG: MAIN
500   => TESTSUITE/test-mail/junk <rd+usery@test.ex> R=r5 T=ft1
501 --------> TESTSUITE/test-mail/junk <--------
502 locking TESTSUITE/spool/db/retry.lockfile
503 locked  TESTSUITE/spool/db/retry.lockfile
504 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
505 returned from EXIM_DBOPEN
506 no retry data available
507 search_tidyup called
508 changed uid/gid: local delivery to TESTSUITE/test-mail/junk <TESTSUITE/test-mail/junk> transport=ft1
509   uid=CALLER_UID gid=CALLER_GID pid=pppp
510   home=/non-exist/CALLER current=/
511 set_process_info: pppp delivering 10HmaX-0005vi-00 to TESTSUITE/test-mail/junk using ft1
512 appendfile transport entered
513  ┌considering: $address_file
514  ├──expanding: $address_file
515  └─────result: TESTSUITE/test-mail/junk
516 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
517   file=TESTSUITE/test-mail/junk format=unix
518   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
519   message_suffix=\n
520   maildir_use_size_file=no
521   locking by lockfile fcntl 
522 lock name: TESTSUITE/test-mail/junk.lock
523 hitch name: TESTSUITE/test-mail/junk.lock.test.ex.dddddddd.pppppppp
524 lock file created
525 mailbox TESTSUITE/test-mail/junk is locked
526 writing to file TESTSUITE/test-mail/junk
527  ┌considering: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
528  
529  ├──condition: def:return_path
530  ├─────result: true
531   ┌considering: $return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
532   
533   ├──expanding: $return_path
534   └─────result: CALLER@test.ex
535   ┌───scanning: MAILER-DAEMON}} ${tod_bsdinbox}
536   
537   ├──expanding: MAILER-DAEMON
538   ├─────result: MAILER-DAEMON
539   └───skipping: result is not used
540  ├──expanding: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
541  
542  └─────result: From CALLER@test.ex Tue Mar 02 09:44:33 1999
543  
544 writing data block fd=dddd size=sss timeout=0
545 writing data block fd=dddd size=sss timeout=0
546 writing data block fd=dddd size=sss timeout=0
547 appendfile yields 0 with errno=dd more_errno=dd
548 search_tidyup called
549 journalling TESTSUITE/test-mail/junk:rd+CALLER@test.ex
550 ft1 transport returned OK for TESTSUITE/test-mail/junk
551 post-process TESTSUITE/test-mail/junk (0)
552 TESTSUITE/test-mail/junk delivered
553 rd+CALLER@test.ex: children all complete
554 LOG: MAIN
555   => TESTSUITE/test-mail/junk <rd+CALLER@test.ex> R=r4 T=ft1
556 --------> CALLER@test.ex <--------
557 locking TESTSUITE/spool/db/retry.lockfile
558 locked  TESTSUITE/spool/db/retry.lockfile
559 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
560 returned from EXIM_DBOPEN
561 no retry data available
562 search_tidyup called
563 changed uid/gid: local delivery to CALLER <CALLER@test.ex> transport=t1
564   uid=CALLER_UID gid=CALLER_GID pid=pppp
565   home=/non-exist/CALLER current=/
566 set_process_info: pppp delivering 10HmaX-0005vi-00 to CALLER using t1
567 appendfile transport entered
568 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
569   file=/dev/null format=unix
570   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
571   message_suffix=\n
572   maildir_use_size_file=no
573   locking by lockfile fcntl 
574 search_tidyup called
575 journalling CALLER@test.ex
576 t1 transport returned OK for CALLER@test.ex
577 post-process CALLER@test.ex (0)
578 CALLER@test.ex delivered
579 LOG: MAIN
580   => CALLER <CALLER@test.ex> R=r1 T=t1
581 --------> usery@test.ex <--------
582 locking TESTSUITE/spool/db/retry.lockfile
583 locked  TESTSUITE/spool/db/retry.lockfile
584 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
585 returned from EXIM_DBOPEN
586 no retry data available
587  ┌considering: /non-exist/$local_part
588  ├──expanding: /non-exist/$local_part
589  └─────result: /non-exist/usery
590 search_tidyup called
591 changed uid/gid: local delivery to usery <usery@test.ex> transport=t1
592   uid=CALLER_UID gid=CALLER_GID pid=pppp
593   home=/non-exist/usery current=/
594 set_process_info: pppp delivering 10HmaX-0005vi-00 to usery using t1
595 appendfile transport entered
596 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
597   file=/dev/null format=unix
598   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
599   message_suffix=\n
600   maildir_use_size_file=no
601   locking by lockfile fcntl 
602 search_tidyup called
603 journalling usery@test.ex
604 t1 transport returned OK for usery@test.ex
605 post-process usery@test.ex (0)
606 usery@test.ex delivered
607 LOG: MAIN
608   => usery <usery@test.ex> R=r2 T=t1
609 --------> userz@test.ex <--------
610 locking TESTSUITE/spool/db/retry.lockfile
611 locked  TESTSUITE/spool/db/retry.lockfile
612 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
613 returned from EXIM_DBOPEN
614 no retry data available
615  ┌considering: /$local_part
616  ├──expanding: /$local_part
617  └─────result: /userz
618 search_tidyup called
619 changed uid/gid: local delivery to userz <userz@test.ex> transport=t2
620   uid=CALLER_UID gid=CALLER_GID pid=pppp
621   home=/userz current=/
622 set_process_info: pppp delivering 10HmaX-0005vi-00 to userz using t2
623 appendfile transport entered
624 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
625   file=/dev/null format=unix
626   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
627   message_suffix=\n
628   maildir_use_size_file=no
629   locking by lockfile fcntl 
630 search_tidyup called
631 journalling userz@test.ex
632 t2 transport returned OK for userz@test.ex
633 post-process userz@test.ex (0)
634 userz@test.ex delivered
635 LOG: MAIN
636   => userz <userz@test.ex> R=r3 T=t2
637 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
638 changed uid/gid: post-delivery tidying
639   uid=EXIM_UID gid=EXIM_GID pid=pppp
640 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
641 Processing retry items
642 Succeeded addresses:
643  userz@test.ex: no retry items
644  usery@test.ex: no retry items
645  CALLER@test.ex: no retry items
646  TESTSUITE/test-mail/junk: no retry items
647  rd+CALLER@test.ex: no retry items
648  TESTSUITE/test-mail/junk: no retry items
649  rd+usery@test.ex: no retry items
650  rd+CALLER@test.ex: no retry items
651  rd+usery@test.ex: no retry items
652 Failed addresses:
653 Deferred addresses:
654 end of retry processing
655 DSN: processing router : r3
656 DSN: processing successful delivery address: userz@test.ex
657 DSN: Sender_address: CALLER@test.ex
658 DSN: orcpt: NULL  flags: 0
659 DSN: envid: NULL  ret: 0
660 DSN: Final recipient: userz@test.ex
661 DSN: Remote SMTP server supports DSN: 0
662 DSN: not sending DSN success message
663 DSN: processing router : r2
664 DSN: processing successful delivery address: usery@test.ex
665 DSN: Sender_address: CALLER@test.ex
666 DSN: orcpt: NULL  flags: 0
667 DSN: envid: NULL  ret: 0
668 DSN: Final recipient: usery@test.ex
669 DSN: Remote SMTP server supports DSN: 0
670 DSN: not sending DSN success message
671 DSN: processing router : r1
672 DSN: processing successful delivery address: CALLER@test.ex
673 DSN: Sender_address: CALLER@test.ex
674 DSN: orcpt: NULL  flags: 0
675 DSN: envid: NULL  ret: 0
676 DSN: Final recipient: CALLER@test.ex
677 DSN: Remote SMTP server supports DSN: 0
678 DSN: not sending DSN success message
679 DSN: processing router : r4
680 DSN: processing successful delivery address: TESTSUITE/test-mail/junk
681 DSN: Sender_address: CALLER@test.ex
682 DSN: orcpt: NULL  flags: 0
683 DSN: envid: NULL  ret: 0
684 DSN: Final recipient: TESTSUITE/test-mail/junk
685 DSN: Remote SMTP server supports DSN: 0
686 DSN: not sending DSN success message
687 DSN: processing router : r5
688 DSN: processing successful delivery address: TESTSUITE/test-mail/junk
689 DSN: Sender_address: CALLER@test.ex
690 DSN: orcpt: NULL  flags: 0
691 DSN: envid: NULL  ret: 0
692 DSN: Final recipient: TESTSUITE/test-mail/junk
693 DSN: Remote SMTP server supports DSN: 0
694 DSN: not sending DSN success message
695 DSN: processing router : r4
696 DSN: processing successful delivery address: rd+CALLER@test.ex
697 DSN: Sender_address: CALLER@test.ex
698 DSN: orcpt: NULL  flags: 0
699 DSN: envid: NULL  ret: 0
700 DSN: Final recipient: rd+CALLER@test.ex
701 DSN: Remote SMTP server supports DSN: 0
702 DSN: not sending DSN success message
703 DSN: processing router : r5
704 DSN: processing successful delivery address: rd+usery@test.ex
705 DSN: Sender_address: CALLER@test.ex
706 DSN: orcpt: NULL  flags: 0
707 DSN: envid: NULL  ret: 0
708 DSN: Final recipient: rd+usery@test.ex
709 DSN: Remote SMTP server supports DSN: 0
710 DSN: not sending DSN success message
711 LOG: MAIN
712   Completed
713 end delivery of 10HmaX-0005vi-00
714 search_tidyup called
715 search_tidyup called
716 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
717 search_tidyup called
718 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>