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