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