Debug: more info during server-side authentication
[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 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 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(TESTSUITE/spool/db/retry)
256 returned from EXIM_DBOPEN
257 ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
258 no retry data available
259 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
260 Considering: 2@b
261 unique = 2@b
262 no domain retry record
263 no address retry record
264 2@b: queued for routing
265 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
266 routing 2@b
267 --------> r1 router <--------
268 local_part=2 domain=b
269 calling r1 router
270 r1 router called for 2@b
271   domain = b
272 set transport t1
273 queued for t1 transport: local_part = 2
274 domain = b
275   errors_to=NULL
276   domain_data=NULL localpart_data=NULL
277 routed by r1 router
278   envelope to: 2@b
279   transport: t1
280 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
281 After routing:
282   Local deliveries:
283     2@b
284   Remote deliveries:
285   Failed addresses:
286   Deferred addresses:
287 search_tidyup called
288 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
289 --------> 2@b <--------
290 locking TESTSUITE/spool/db/retry.lockfile
291 locked  TESTSUITE/spool/db/retry.lockfile
292 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
293 returned from EXIM_DBOPEN
294 no retry data available
295 search_tidyup called
296 changed uid/gid: local delivery to 2 <2@b> transport=t1
297   uid=CALLER_UID gid=CALLER_GID pid=pppp
298   home=NULL current=/
299 set_process_info: pppp delivering 10HmaX-0005vi-00 to 2 using t1
300 appendfile transport entered
301 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
302   file=TESTSUITE/test-mail/2 format=unix
303   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
304   message_suffix=\n
305   maildir_use_size_file=no
306   locking by lockfile fcntl 
307 lock name: TESTSUITE/test-mail/2.lock
308 hitch name: TESTSUITE/test-mail/2.lock.test.ex.dddddddd.pppppppp
309 lock file created
310 mailbox TESTSUITE/test-mail/2 is locked
311 writing to file TESTSUITE/test-mail/2
312 writing data block fd=dddd size=sss timeout=0
313 cannot use sendfile for body: spoolfile not wireformat
314 writing data block fd=dddd size=sss timeout=0
315 writing data block fd=dddd size=sss timeout=0
316 appendfile yields 0 with errno=dd more_errno=dd
317 search_tidyup called
318 journalling 2@b
319 t1 transport returned OK for 2@b
320 post-process 2@b (0)
321 2@b delivered
322 LOG: MAIN
323   => 2 <2@b> R=r1 T=t1
324 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
325 changed uid/gid: post-delivery tidying
326   uid=EXIM_UID gid=EXIM_GID pid=pppp
327 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
328 Processing retry items
329 Succeeded addresses:
330  2@b: no retry items
331 Failed addresses:
332 Deferred addresses:
333 end of retry processing
334 DSN: processing router : r1
335 DSN: processing successful delivery address: 2@b
336 DSN: Sender_address: x@y
337 DSN: orcpt: NULL  flags: 0
338 DSN: envid: NULL  ret: 0
339 DSN: Final recipient: 2@b
340 DSN: Remote SMTP server supports DSN: 0
341 DSN: not sending DSN success message
342 LOG: MAIN
343   Completed
344 end delivery of 10HmaX-0005vi-00
345 search_tidyup called
346 search_tidyup called
347 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
348 smtp_setup_msg entered
349 SMTP<< rset
350 SMTP>> 250 Reset OK
351 SMTP<< mail from:<x@y>
352 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
353 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
354 SMTP>> 250 OK
355 SMTP<< rcpt to:<2@b>
356 using ACL "TESTSUITE/aux-fixed/0386.acl2"
357 processing "warn"
358   message: X-Warning: $sender_host_address is listed at $dnslist_domain\nX-Warning: $dnslist_text
359 l_message: found in $dnslist_domain: $dnslist_text
360 check dnslists = rbl.test.ex 
361 DNS list check: rbl.test.ex
362 using result of previous DNS lookup
363 DNS lookup for 13.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
364 => that means V4NET.11.12.13 is listed at rbl.test.ex
365 warn: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2"
366 LOG: MAIN
367   H=[V4NET.11.12.13] U=CALLER Warning: found in rbl.test.ex: This is a test blacklisting message
368 processing "accept"
369 accept: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2"
370 end of ACL "TESTSUITE/aux-fixed/0386.acl2": ACCEPT
371 SMTP>> 250 Accepted
372 DSN: orcpt: NULL  flags: 0
373 SMTP<< data
374 SMTP>> 354 Enter message, ending with "." on a line by itself
375 search_tidyup called
376 >>Headers received:
377
378 search_tidyup called
379 >>Headers after rewriting and local additions:
380
381 Data file name: TESTSUITE/spool//input//10HmaY-0005vi-00-D
382 Data file written for message 10HmaY-0005vi-00
383 >>Generated Received: header line
384 P Received: from [V4NET.11.12.13] (ident=CALLER)
385         by myhost.test.ex with smtp (Exim x.yz)
386         (envelope-from <x@y>)
387         id 10HmaY-0005vi-00
388         for 2@b; Tue, 2 Mar 1999 09:44:33 +0000
389 >>Headers added by MAIL or RCPT ACL:
390   X-Warning: V4NET.11.12.13 is listed at rbl.test.ex
391   X-Warning: This is a test blacklisting message
392 >>
393 calling local_scan(); timeout=300
394 local_scan() returned 0 NULL
395 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
396 DSN: Write SPOOL :-dsn_envid NULL
397 DSN: Write SPOOL :-dsn_ret 0
398 DSN: Flags :0
399 DSN: **** SPOOL_OUT - address: |2@b| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
400 Renaming spool header file: TESTSUITE/spool//input//10HmaY-0005vi-00-H
401 Size of headers = sss
402 LOG: MAIN
403   <= x@y H=[V4NET.11.12.13] U=CALLER P=smtp S=sss
404 SMTP>> 250 OK id=10HmaY-0005vi-00
405 search_tidyup called
406 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmaY-0005vi-00
407 Exim version x.yz ....
408 changed uid/gid: forcing real = effective
409   uid=uuuu gid=EXIM_GID pid=pppp
410 seeking password data for user "CALLER": cache not available
411 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
412 configuration file is TESTSUITE/test-config
413 trusted user
414 admin user
415 DSN: r1 propagating DSN
416 seeking password data for user "CALLER": using cached result
417 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
418 set_process_info: pppp delivering specified messages
419 set_process_info: pppp delivering 10HmaY-0005vi-00
420 Trying spool file TESTSUITE/spool//input//10HmaY-0005vi-00-D
421 reading spool file 10HmaY-0005vi-00-H
422 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=x@y
423 sender_fullhost = [V4NET.11.12.13]
424 sender_rcvhost = [V4NET.11.12.13] (ident=CALLER)
425 sender_local=0 ident=CALLER
426 Non-recipients:
427 Empty Tree
428 ---- End of tree ----
429 recipients_count=1
430 **** SPOOL_IN - No additional fields
431 body_linecount=1 message_linecount=7
432 DSN: set orcpt: NULL  flags: 0
433 Delivery address list:
434   2@b 
435 locking TESTSUITE/spool/db/retry.lockfile
436 locked  TESTSUITE/spool/db/retry.lockfile
437 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
438 returned from EXIM_DBOPEN
439 no retry data available
440 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
441 Considering: 2@b
442 unique = 2@b
443 no domain retry record
444 no address retry record
445 2@b: queued for routing
446 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
447 routing 2@b
448 --------> r1 router <--------
449 local_part=2 domain=b
450 calling r1 router
451 r1 router called for 2@b
452   domain = b
453 set transport t1
454 queued for t1 transport: local_part = 2
455 domain = b
456   errors_to=NULL
457   domain_data=NULL localpart_data=NULL
458 routed by r1 router
459   envelope to: 2@b
460   transport: t1
461 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
462 After routing:
463   Local deliveries:
464     2@b
465   Remote deliveries:
466   Failed addresses:
467   Deferred addresses:
468 search_tidyup called
469 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
470 --------> 2@b <--------
471 locking TESTSUITE/spool/db/retry.lockfile
472 locked  TESTSUITE/spool/db/retry.lockfile
473 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
474 returned from EXIM_DBOPEN
475 no retry data available
476 search_tidyup called
477 changed uid/gid: local delivery to 2 <2@b> transport=t1
478   uid=CALLER_UID gid=CALLER_GID pid=pppp
479   home=NULL current=/
480 set_process_info: pppp delivering 10HmaY-0005vi-00 to 2 using t1
481 appendfile transport entered
482 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
483   file=TESTSUITE/test-mail/2 format=unix
484   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
485   message_suffix=\n
486   maildir_use_size_file=no
487   locking by lockfile fcntl 
488 lock name: TESTSUITE/test-mail/2.lock
489 hitch name: TESTSUITE/test-mail/2.lock.test.ex.dddddddd.pppppppp
490 lock file created
491 mailbox TESTSUITE/test-mail/2 is locked
492 writing to file TESTSUITE/test-mail/2
493 writing data block fd=dddd size=sss timeout=0
494 cannot use sendfile for body: spoolfile not wireformat
495 writing data block fd=dddd size=sss timeout=0
496 writing data block fd=dddd size=sss timeout=0
497 appendfile yields 0 with errno=dd more_errno=dd
498 search_tidyup called
499 journalling 2@b
500 t1 transport returned OK for 2@b
501 post-process 2@b (0)
502 2@b delivered
503 LOG: MAIN
504   => 2 <2@b> R=r1 T=t1
505 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
506 changed uid/gid: post-delivery tidying
507   uid=EXIM_UID gid=EXIM_GID pid=pppp
508 set_process_info: pppp tidying up after delivering 10HmaY-0005vi-00
509 Processing retry items
510 Succeeded addresses:
511  2@b: no retry items
512 Failed addresses:
513 Deferred addresses:
514 end of retry processing
515 DSN: processing router : r1
516 DSN: processing successful delivery address: 2@b
517 DSN: Sender_address: x@y
518 DSN: orcpt: NULL  flags: 0
519 DSN: envid: NULL  ret: 0
520 DSN: Final recipient: 2@b
521 DSN: Remote SMTP server supports DSN: 0
522 DSN: not sending DSN success message
523 LOG: MAIN
524   Completed
525 end delivery of 10HmaY-0005vi-00
526 search_tidyup called
527 search_tidyup called
528 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
529 smtp_setup_msg entered
530 SMTP<< quit
531 SMTP>> 221 myhost.test.ex closing connection
532 LOG: smtp_connection MAIN
533   SMTP connection from CALLER closed by QUIT
534 search_tidyup called
535 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>