Send delay-MDN for any queurun past delay_warning, even if not retry time yet. Bug...
[users/heiko/exim.git] / test / stderr / 0386
1 Exim version x.yz ....
2 changed uid/gid: forcing real = effective
3   uid=uuuu gid=CALLER_GID pid=pppp
4 seeking password data for user "CALLER": cache not available
5 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
6 configuration file is TESTSUITE/test-config
7 trusted user
8 admin user
9 changed uid/gid: privilege not needed
10   uid=EXIM_UID gid=EXIM_GID pid=pppp
11 seeking password data for user "CALLER": using cached result
12 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
13 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
14 sender address = CALLER@test.ex
15 sender_fullhost = [V4NET.9.8.7]
16 sender_rcvhost = [V4NET.9.8.7]
17 host in hosts_connection_nolog? no (option unset)
18 LOG: smtp_connection MAIN
19   SMTP connection from [V4NET.9.8.7]
20 host in host_lookup? no (option unset)
21 set_process_info: pppp handling incoming connection from [V4NET.9.8.7]
22 host in host_reject_connection? no (option unset)
23 host in sender_unqualified_hosts? no (option unset)
24 host in recipient_unqualified_hosts? no (option unset)
25 host in helo_verify_hosts? no (option unset)
26 host in helo_try_verify_hosts? no (option unset)
27 host in helo_accept_junk_hosts? no (option unset)
28 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
29 smtp_setup_msg entered
30 SMTP<< mail from:<x@y>
31 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
32 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
33 SMTP>> 250 OK
34 SMTP<< rcpt to:<1@b>
35 read ACL from file TESTSUITE/aux-fixed/0386.acl1
36 processing "accept"
37 check hosts = :
38 host in ":"? no (end of list)
39 accept: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1"
40 processing "deny"
41 check local_parts = ^.*[@%!/|]
42 1 in "^.*[@%!/|]"? no (end of list)
43 deny: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1"
44 processing "require"
45 l_message: Invalid sender
46   message: Couldn't verify the sender
47 check verify = sender/defer_ok
48 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
49 Verifying x@y
50 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
51 Considering x@y
52 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
53 routing x@y
54 --------> r1 router <--------
55 local_part=x domain=y
56 calling r1 router
57 r1 router called for x@y
58   domain = y
59 set transport t1
60 queued for t1 transport: local_part = x
61 domain = y
62   errors_to=NULL
63   domain_data=NULL localpart_data=NULL
64 routed by r1 router
65   envelope to: x@y
66   transport: t1
67 ----------- end verify ------------
68 sender x@y verified ok
69 require: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1"
70 processing "deny"
71   message: No such user here
72 deny: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1"
73 end of ACL "TESTSUITE/aux-fixed/0386.acl1": DENY
74 SMTP>> 550 No such user here
75 LOG: MAIN REJECT
76   H=[V4NET.9.8.7] F=<x@y> rejected RCPT <1@b>: No such user here
77 SMTP<< rset
78 SMTP>> 250 Reset OK
79 SMTP<< mail from:<x@y>
80 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
81 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
82 SMTP>> 250 OK
83 SMTP<< rcpt to:<1@b>
84 using ACL "TESTSUITE/aux-fixed/0386.acl1"
85 processing "accept"
86 check hosts = :
87 host in ":"? no (end of list)
88 accept: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1"
89 processing "deny"
90 check local_parts = ^.*[@%!/|]
91 1 in "^.*[@%!/|]"? no (end of list)
92 deny: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1"
93 processing "require"
94 l_message: Invalid sender
95   message: Couldn't verify the sender
96 check verify = sender/defer_ok
97 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
98 Verifying x@y
99 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
100 Considering x@y
101 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
102 routing x@y
103 --------> r1 router <--------
104 local_part=x domain=y
105 calling r1 router
106 r1 router called for x@y
107   domain = y
108 queued for t1 transport: local_part = x
109 domain = y
110   errors_to=NULL
111   domain_data=NULL localpart_data=NULL
112 routed by r1 router
113   envelope to: x@y
114   transport: t1
115 ----------- end verify ------------
116 sender x@y verified ok
117 require: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1"
118 processing "deny"
119   message: No such user here
120 deny: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1"
121 end of ACL "TESTSUITE/aux-fixed/0386.acl1": DENY
122 SMTP>> 550 No such user here
123 LOG: MAIN REJECT
124   H=[V4NET.9.8.7] F=<x@y> rejected RCPT <1@b>: No such user here
125 SMTP<< quit
126 SMTP>> 221 myhost.test.ex closing connection
127 LOG: smtp_connection MAIN
128   SMTP connection from [V4NET.9.8.7] closed by QUIT
129 search_tidyup called
130 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
131 Exim version x.yz ....
132 changed uid/gid: forcing real = effective
133   uid=uuuu gid=CALLER_GID pid=pppp
134 seeking password data for user "CALLER": cache not available
135 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
136 configuration file is TESTSUITE/test-config
137 trusted user
138 admin user
139 changed uid/gid: privilege not needed
140   uid=EXIM_UID gid=EXIM_GID pid=pppp
141 seeking password data for user "CALLER": using cached result
142 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
143 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
144 sender address = NULL
145 sender_fullhost = [V4NET.11.12.13]
146 sender_rcvhost = [V4NET.11.12.13] (ident=CALLER)
147 set_process_info: pppp handling incoming connection from [V4NET.11.12.13] via -oMa
148 set_process_info: pppp accepting a local SMTP message from <CALLER>
149 host in hosts_connection_nolog? no (option unset)
150 LOG: smtp_connection MAIN
151   SMTP connection from CALLER
152 host in host_lookup? no (option unset)
153 set_process_info: pppp handling incoming connection from [V4NET.11.12.13] U=CALLER
154 host in host_reject_connection? no (option unset)
155 host in sender_unqualified_hosts? no (option unset)
156 host in recipient_unqualified_hosts? no (option unset)
157 host in helo_verify_hosts? no (option unset)
158 host in helo_try_verify_hosts? no (option unset)
159 host in helo_accept_junk_hosts? no (option unset)
160 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
161 smtp_setup_msg entered
162 SMTP<< mail from:<x@y>
163 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
164 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
165 SMTP>> 250 OK
166 SMTP<< rcpt to:<2@b>
167 read ACL from file TESTSUITE/aux-fixed/0386.acl2
168 processing "warn"
169   message: X-Warning: $sender_host_address is listed at $dnslist_domain\nX-Warning: $dnslist_text
170 l_message: found in $dnslist_domain: $dnslist_text
171 check dnslists = rbl.test.ex 
172 DNS list check: rbl.test.ex
173 new DNS lookup for 13.12.11.V4NET.rbl.test.ex
174 DNS lookup of 13.12.11.V4NET.rbl.test.ex (A) using fakens
175 DNS lookup of 13.12.11.V4NET.rbl.test.ex (A) succeeded
176 DNS lookup for 13.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
177 DNS lookup of 13.12.11.V4NET.rbl.test.ex (TXT) using fakens
178 DNS lookup of 13.12.11.V4NET.rbl.test.ex (TXT) succeeded
179 => that means V4NET.11.12.13 is listed at rbl.test.ex
180 warn: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2"
181 LOG: MAIN
182   H=[V4NET.11.12.13] U=CALLER Warning: found in rbl.test.ex: This is a test blacklisting message
183 created log directory TESTSUITE/spool/log
184 processing "accept"
185 accept: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2"
186 end of ACL "TESTSUITE/aux-fixed/0386.acl2": ACCEPT
187 SMTP>> 250 Accepted
188 DSN: orcpt: NULL  flags: 0
189 SMTP<< data
190 SMTP>> 354 Enter message, ending with "." on a line by itself
191 search_tidyup called
192 >>Headers received:
193
194 search_tidyup called
195 >>Headers after rewriting and local additions:
196
197 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
198 Data file written for message 10HmaX-0005vi-00
199 >>Generated Received: header line
200 P Received: from [V4NET.11.12.13] (ident=CALLER)
201         by myhost.test.ex with smtp (Exim x.yz)
202         (envelope-from <x@y>)
203         id 10HmaX-0005vi-00
204         for 2@b; Tue, 2 Mar 1999 09:44:33 +0000
205 >>Headers added by MAIL or RCPT ACL:
206   X-Warning: V4NET.11.12.13 is listed at rbl.test.ex
207   X-Warning: This is a test blacklisting message
208 >>
209 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
210 DSN: Write SPOOL :-dsn_envid NULL
211 DSN: Write SPOOL :-dsn_ret 0
212 DSN: Flags :0
213 DSN: **** SPOOL_OUT - address: |2@b| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
214 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
215 Size of headers = sss
216 LOG: MAIN
217   <= x@y H=[V4NET.11.12.13] U=CALLER P=smtp S=sss
218 SMTP>> 250 OK id=10HmaX-0005vi-00
219 search_tidyup called
220 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -odi -Mc 10HmaX-0005vi-00
221 Exim version x.yz ....
222 changed uid/gid: forcing real = effective
223   uid=uuuu gid=EXIM_GID pid=pppp
224 seeking password data for user "CALLER": cache not available
225 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
226 configuration file is TESTSUITE/test-config
227 trusted user
228 admin user
229 seeking password data for user "CALLER": using cached result
230 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
231 set_process_info: pppp delivering specified messages
232 set_process_info: pppp delivering 10HmaX-0005vi-00
233 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
234 reading spool file 10HmaX-0005vi-00-H
235 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=x@y
236 sender_fullhost = [V4NET.11.12.13]
237 sender_rcvhost = [V4NET.11.12.13] (ident=CALLER)
238 sender_local=0 ident=CALLER
239 Non-recipients:
240 Empty Tree
241 ---- End of tree ----
242 recipients_count=1
243 **** SPOOL_IN - No additional fields
244 body_linecount=1 message_linecount=7
245 DSN: set orcpt:   flags: 0
246 Delivery address list:
247   2@b 
248  locking TESTSUITE/spool/db/retry.lockfile
249  locked  TESTSUITE/spool/db/retry.lockfile
250  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
251  returned from EXIM_DBOPEN: (nil)
252  ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
253  failed to open DB file TESTSUITE/spool/db/retry.lockfile: No such file or directory
254 no retry data available
255 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
256 Considering: 2@b
257 unique = 2@b
258 no   domain  retry record
259 no   address retry record
260 2@b: queued for routing
261 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
262 routing 2@b
263 --------> r1 router <--------
264 local_part=2 domain=b
265 calling r1 router
266 r1 router called for 2@b
267   domain = b
268 set transport t1
269 queued for t1 transport: local_part = 2
270 domain = b
271   errors_to=NULL
272   domain_data=NULL localpart_data=NULL
273 routed by r1 router
274   envelope to: 2@b
275   transport: t1
276 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
277 After routing:
278   Local deliveries:
279     2@b
280   Remote deliveries:
281   Failed addresses:
282   Deferred addresses:
283 search_tidyup called
284 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
285 --------> 2@b <--------
286  locking TESTSUITE/spool/db/retry.lockfile
287  locked  TESTSUITE/spool/db/retry.lockfile
288  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
289  returned from EXIM_DBOPEN: (nil)
290  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
291 no retry data available
292 search_tidyup called
293 changed uid/gid: local delivery to 2 <2@b> transport=t1
294   uid=CALLER_UID gid=CALLER_GID pid=pppp
295   home=NULL current=/
296 set_process_info: pppp delivering 10HmaX-0005vi-00 to 2 using t1
297 appendfile transport entered
298 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
299   file=TESTSUITE/test-mail/2 format=unix
300   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
301   message_suffix=\n
302   maildir_use_size_file=no
303   locking by lockfile fcntl 
304 lock name: TESTSUITE/test-mail/2.lock
305 hitch name: TESTSUITE/test-mail/2.lock.test.ex.dddddddd.pppppppp
306 lock file created
307 mailbox TESTSUITE/test-mail/2 is locked
308 writing to file TESTSUITE/test-mail/2
309 writing data block fd=dddd size=sss timeout=0
310 cannot use sendfile for body: spoolfile not wireformat
311 writing data block fd=dddd size=sss timeout=0
312 writing data block fd=dddd size=sss timeout=0
313 appendfile yields 0 with errno=dd more_errno=dd
314 search_tidyup called
315 journalling 2@b
316 t1 transport returned OK for 2@b
317 post-process 2@b (0)
318 2@b delivered
319 LOG: MAIN
320   => 2 <2@b> R=r1 T=t1
321 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
322 changed uid/gid: post-delivery tidying
323   uid=EXIM_UID gid=EXIM_GID pid=pppp
324 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
325 Processing retry items
326 Succeeded addresses:
327  2@b: no retry items
328 Failed addresses:
329 Deferred addresses:
330 end of retry processing
331 DSN: processing router : r1
332 DSN: processing successful delivery address: 2@b
333 DSN: Sender_address: x@y
334 DSN: orcpt: NULL  flags: 0
335 DSN: envid: NULL  ret: 0
336 DSN: Final recipient: 2@b
337 DSN: Remote SMTP server supports DSN: 0
338 DSN: not sending DSN success message
339 LOG: MAIN
340   Completed
341 end delivery of 10HmaX-0005vi-00
342 search_tidyup called
343 search_tidyup called
344 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
345 smtp_setup_msg entered
346 SMTP<< rset
347 SMTP>> 250 Reset OK
348 SMTP<< mail from:<x@y>
349 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
350 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
351 SMTP>> 250 OK
352 SMTP<< rcpt to:<2@b>
353 using ACL "TESTSUITE/aux-fixed/0386.acl2"
354 processing "warn"
355   message: X-Warning: $sender_host_address is listed at $dnslist_domain\nX-Warning: $dnslist_text
356 l_message: found in $dnslist_domain: $dnslist_text
357 check dnslists = rbl.test.ex 
358 DNS list check: rbl.test.ex
359 using result of previous DNS lookup
360 DNS lookup for 13.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
361 => that means V4NET.11.12.13 is listed at rbl.test.ex
362 warn: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2"
363 LOG: MAIN
364   H=[V4NET.11.12.13] U=CALLER Warning: found in rbl.test.ex: This is a test blacklisting message
365 processing "accept"
366 accept: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2"
367 end of ACL "TESTSUITE/aux-fixed/0386.acl2": ACCEPT
368 SMTP>> 250 Accepted
369 DSN: orcpt: NULL  flags: 0
370 SMTP<< data
371 SMTP>> 354 Enter message, ending with "." on a line by itself
372 search_tidyup called
373 >>Headers received:
374
375 search_tidyup called
376 >>Headers after rewriting and local additions:
377
378 Data file name: TESTSUITE/spool//input//10HmaY-0005vi-00-D
379 Data file written for message 10HmaY-0005vi-00
380 >>Generated Received: header line
381 P Received: from [V4NET.11.12.13] (ident=CALLER)
382         by myhost.test.ex with smtp (Exim x.yz)
383         (envelope-from <x@y>)
384         id 10HmaY-0005vi-00
385         for 2@b; Tue, 2 Mar 1999 09:44:33 +0000
386 >>Headers added by MAIL or RCPT ACL:
387   X-Warning: V4NET.11.12.13 is listed at rbl.test.ex
388   X-Warning: This is a test blacklisting message
389 >>
390 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
391 DSN: Write SPOOL :-dsn_envid NULL
392 DSN: Write SPOOL :-dsn_ret 0
393 DSN: Flags :0
394 DSN: **** SPOOL_OUT - address: |2@b| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
395 Renaming spool header file: TESTSUITE/spool//input//10HmaY-0005vi-00-H
396 Size of headers = sss
397 LOG: MAIN
398   <= x@y H=[V4NET.11.12.13] U=CALLER P=smtp S=sss
399 SMTP>> 250 OK id=10HmaY-0005vi-00
400 search_tidyup called
401 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -odi -Mc 10HmaY-0005vi-00
402 Exim version x.yz ....
403 changed uid/gid: forcing real = effective
404   uid=uuuu gid=EXIM_GID pid=pppp
405 seeking password data for user "CALLER": cache not available
406 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
407 configuration file is TESTSUITE/test-config
408 trusted user
409 admin user
410 seeking password data for user "CALLER": using cached result
411 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
412 set_process_info: pppp delivering specified messages
413 set_process_info: pppp delivering 10HmaY-0005vi-00
414 Trying spool file TESTSUITE/spool//input//10HmaY-0005vi-00-D
415 reading spool file 10HmaY-0005vi-00-H
416 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=x@y
417 sender_fullhost = [V4NET.11.12.13]
418 sender_rcvhost = [V4NET.11.12.13] (ident=CALLER)
419 sender_local=0 ident=CALLER
420 Non-recipients:
421 Empty Tree
422 ---- End of tree ----
423 recipients_count=1
424 **** SPOOL_IN - No additional fields
425 body_linecount=1 message_linecount=7
426 DSN: set orcpt:   flags: 0
427 Delivery address list:
428   2@b 
429  locking TESTSUITE/spool/db/retry.lockfile
430  locked  TESTSUITE/spool/db/retry.lockfile
431  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
432  returned from EXIM_DBOPEN: (nil)
433  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
434 no retry data available
435 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
436 Considering: 2@b
437 unique = 2@b
438 no   domain  retry record
439 no   address retry record
440 2@b: queued for routing
441 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
442 routing 2@b
443 --------> r1 router <--------
444 local_part=2 domain=b
445 calling r1 router
446 r1 router called for 2@b
447   domain = b
448 set transport t1
449 queued for t1 transport: local_part = 2
450 domain = b
451   errors_to=NULL
452   domain_data=NULL localpart_data=NULL
453 routed by r1 router
454   envelope to: 2@b
455   transport: t1
456 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
457 After routing:
458   Local deliveries:
459     2@b
460   Remote deliveries:
461   Failed addresses:
462   Deferred addresses:
463 search_tidyup called
464 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
465 --------> 2@b <--------
466  locking TESTSUITE/spool/db/retry.lockfile
467  locked  TESTSUITE/spool/db/retry.lockfile
468  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
469  returned from EXIM_DBOPEN: (nil)
470  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
471 no retry data available
472 search_tidyup called
473 changed uid/gid: local delivery to 2 <2@b> transport=t1
474   uid=CALLER_UID gid=CALLER_GID pid=pppp
475   home=NULL current=/
476 set_process_info: pppp delivering 10HmaY-0005vi-00 to 2 using t1
477 appendfile transport entered
478 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
479   file=TESTSUITE/test-mail/2 format=unix
480   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
481   message_suffix=\n
482   maildir_use_size_file=no
483   locking by lockfile fcntl 
484 lock name: TESTSUITE/test-mail/2.lock
485 hitch name: TESTSUITE/test-mail/2.lock.test.ex.dddddddd.pppppppp
486 lock file created
487 mailbox TESTSUITE/test-mail/2 is locked
488 writing to file TESTSUITE/test-mail/2
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 2@b
496 t1 transport returned OK for 2@b
497 post-process 2@b (0)
498 2@b delivered
499 LOG: MAIN
500   => 2 <2@b> R=r1 T=t1
501 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
502 changed uid/gid: post-delivery tidying
503   uid=EXIM_UID gid=EXIM_GID pid=pppp
504 set_process_info: pppp tidying up after delivering 10HmaY-0005vi-00
505 Processing retry items
506 Succeeded addresses:
507  2@b: no retry items
508 Failed addresses:
509 Deferred addresses:
510 end of retry processing
511 DSN: processing router : r1
512 DSN: processing successful delivery address: 2@b
513 DSN: Sender_address: x@y
514 DSN: orcpt: NULL  flags: 0
515 DSN: envid: NULL  ret: 0
516 DSN: Final recipient: 2@b
517 DSN: Remote SMTP server supports DSN: 0
518 DSN: not sending DSN success message
519 LOG: MAIN
520   Completed
521 end delivery of 10HmaY-0005vi-00
522 search_tidyup called
523 search_tidyup called
524 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
525 smtp_setup_msg entered
526 SMTP<< quit
527 SMTP>> 221 myhost.test.ex closing connection
528 LOG: smtp_connection MAIN
529   SMTP connection from CALLER closed by QUIT
530 search_tidyup called
531 >>>>>>>>>>>>>>>> Exim pid=pppp (msg setup toplevel) terminating with rc=0 >>>>>>>>>>>>>>>>