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