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