More detail in file open-fail log line
[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 ACL chk_rcpt "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 ACL TESTSUITE/aux-fixed/0386.acl1 "accept" (TESTSUITE/test-config 18)
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 ACL TESTSUITE/aux-fixed/0386.acl1 "deny" (TESTSUITE/test-config 18)
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 ACL TESTSUITE/aux-fixed/0386.acl1 "require" (TESTSUITE/test-config 18)
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 ACL TESTSUITE/aux-fixed/0386.acl1 "deny" (TESTSUITE/test-config 18)
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 ACL chk_rcpt "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 ACL TESTSUITE/aux-fixed/0386.acl1 "accept" (TESTSUITE/test-config 18)
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 ACL TESTSUITE/aux-fixed/0386.acl1 "deny" (TESTSUITE/test-config 18)
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 ACL TESTSUITE/aux-fixed/0386.acl1 "require" (TESTSUITE/test-config 18)
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 ACL TESTSUITE/aux-fixed/0386.acl1 "deny" (TESTSUITE/test-config 18)
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 ACL chk_rcpt "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 ACL chk_rcpt "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 ACL TESTSUITE/aux-fixed/0386.acl2 "warn" (TESTSUITE/test-config 21)
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 ACL TESTSUITE/aux-fixed/0386.acl2 "accept" (TESTSUITE/test-config 21)
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 no retry data available
316 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
317 Considering: 2@b
318 unique = 2@b
319 checking router retry status
320  no   domain  retry record
321  no   address retry record
322 2@b: queued for routing
323 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
324 routing 2@b
325 --------> r1 router <--------
326 local_part=2 domain=b
327 calling r1 router
328 r1 router called for 2@b
329   domain = b
330 set transport t1
331 queued for t1 transport: local_part = 2
332 domain = b
333   errors_to=NULL
334   domain_data=NULL local_part_data=NULL
335 routed by r1 router
336   envelope to: 2@b
337   transport: t1
338 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
339 After routing:
340   Local deliveries:
341     2@b
342   Remote deliveries:
343   Failed addresses:
344   Deferred addresses:
345 search_tidyup called
346 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
347 --------> 2@b <--------
348 no retry data available
349 search_tidyup called
350 changed uid/gid: local delivery to 2 <2@b> transport=t1
351   uid=CALLER_UID gid=CALLER_GID pid=p1237
352   home=NULL current=/
353 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to 2 using t1
354 appendfile transport entered
355 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
356   file=TESTSUITE/test-mail/2 format=unix
357   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
358   message_suffix=\n
359   maildir_use_size_file=no
360   locking by lockfile fcntl 
361 below-home: de-tainting path 'TESTSUITE/test-mail/2'
362 lock name: TESTSUITE/test-mail/2.lock
363 hitch name: TESTSUITE/test-mail/2.lock.test.ex.dddddddd.pppppppp
364 lock file created
365 mailbox TESTSUITE/test-mail/2 is locked
366 writing to file TESTSUITE/test-mail/2
367 writing data block fd=dddd size=sss timeout=0
368 cannot use sendfile for body: spoolfile not wireformat
369 writing data block fd=dddd size=sss timeout=0
370 writing data block fd=dddd size=sss timeout=0
371 appendfile yields 0 with errno=dd more_errno=dd
372 search_tidyup called
373 >>>>>>>>>>>>>>>> Exim pid=p1237 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>>
374 journalling 2@b
375 t1 transport returned OK for 2@b
376 post-process 2@b (0)
377 2@b delivered
378 LOG: MAIN
379   => 2 <2@b> R=r1 T=t1
380 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
381 changed uid/gid: post-delivery tidying
382   uid=EXIM_UID gid=EXIM_GID pid=p1236
383 set_process_info: pppp tidying up after delivering 10HmaX-000000005vi-0000
384 Processing retry items
385  Succeeded addresses:
386   2@b: no retry items
387  Failed addresses:
388  Deferred addresses:
389 end of retry processing
390 DSN: processing router : r1
391 DSN: processing successful delivery address: 2@b
392 DSN: Sender_address: x@y
393 DSN: orcpt: NULL  flags: 0x0
394 DSN: envid: NULL  ret: 0
395 DSN: Final recipient: 2@b
396 DSN: Remote SMTP server supports DSN: 0
397 DSN: not sending DSN success message
398 LOG: MAIN
399   Completed
400 end delivery of 10HmaX-000000005vi-0000
401 search_tidyup called
402 search_tidyup called
403 >>>>>>>>>>>>>>>> Exim pid=p1236 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
404 smtp_setup_msg entered
405 SMTP<< rset
406 SMTP>> 250 Reset OK
407 SMTP<< mail from:<x@y>
408 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
409 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
410 SMTP>> 250 OK
411 SMTP<< rcpt to:<2@b>
412 using ACL "chk_rcpt"
413 processing ACL chk_rcpt "accept" (TESTSUITE/test-config 18)
414 check local_parts = 1
415 2 in "1"?
416  list element: 1
417 2 in "1"? no (end of list)
418 accept: condition test failed in ACL chk_rcpt
419 processing ACL chk_rcpt "accept" (TESTSUITE/test-config 21)
420 check local_parts = 2
421 2 in "2"?
422  list element: 2
423  2 in "2"? yes (matched "2")
424 check acl = TESTSUITE/aux-fixed/0386.acl2
425  using ACL "TESTSUITE/aux-fixed/0386.acl2"
426  processing ACL TESTSUITE/aux-fixed/0386.acl2 "warn" (TESTSUITE/test-config 21)
427    message: X-Warning: $sender_host_address is listed at $dnslist_domain\nX-Warning: $dnslist_text
428  l_message: found in $dnslist_domain: $dnslist_text
429  check dnslists = rbl.test.ex 
430 dnslists check: rbl.test.ex
431 dnslists: using result of previous lookup
432 DNS lookup for 13.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
433 => that means V4NET.11.12.13 is listed at rbl.test.ex
434  warn: condition test succeeded in ACL TESTSUITE/aux-fixed/0386.acl2
435 LOG: MAIN
436   H=(test) [V4NET.11.12.13] U=CALLER Warning: found in rbl.test.ex: This is a test blacklisting message
437  processing ACL TESTSUITE/aux-fixed/0386.acl2 "accept" (TESTSUITE/test-config 21)
438  accept: condition test succeeded in ACL TESTSUITE/aux-fixed/0386.acl2
439  end of ACL TESTSUITE/aux-fixed/0386.acl2: ACCEPT
440 accept: condition test succeeded in ACL chk_rcpt
441 end of ACL chk_rcpt: ACCEPT
442 SMTP>> 250 Accepted
443 SMTP<< data
444 SMTP>> 354 Enter message, ending with "." on a line by itself
445 search_tidyup called
446 >>Headers received:
447
448 qualify & rewrite recipients list
449 rewrite rules on sender address
450 qualify and rewrite headers
451 search_tidyup called
452 >>Headers after rewriting and local additions:
453
454 Data file name: TESTSUITE/spool//input//10HmaY-000000005vi-0000-D
455 Data file written for message 10HmaY-000000005vi-0000
456 >>Generated Received: header line
457 P Received: from [V4NET.11.12.13] (helo=test ident=CALLER)
458         by myhost.test.ex with smtp (Exim x.yz)
459         (envelope-from <x@y>)
460         id 10HmaY-000000005vi-0000
461         for 2@b;
462         Tue, 2 Mar 1999 09:44:33 +0000
463 >>Headers added by MAIL or RCPT ACL:
464   X-Warning: V4NET.11.12.13 is listed at rbl.test.ex
465   X-Warning: This is a test blacklisting message
466 >>
467 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaY-000000005vi-0000
468 DSN: **** SPOOL_OUT - address: <2@b> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
469 Renaming spool header file: TESTSUITE/spool//input//10HmaY-000000005vi-0000-H
470 Size of headers = sss
471 LOG: MAIN
472   <= x@y H=(test) [V4NET.11.12.13] U=CALLER P=smtp S=sss
473 SMTP>> 250 OK id=10HmaY-000000005vi-0000
474 search_tidyup called
475 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaY-000000005vi-0000
476 Exim version x.yz ....
477 Hints DB:
478 changed uid/gid: forcing real = effective
479   uid=uuuu gid=EXIM_GID pid=p1238
480 seeking password data for user "CALLER": cache not available
481 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
482 configuration file is TESTSUITE/test-config
483 trusted user
484 admin user
485 dropping to exim gid; retaining priv uid
486 seeking password data for user "CALLER": using cached result
487 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
488 set_process_info: pppp delivering specified messages
489 set_process_info: pppp delivering 10HmaY-000000005vi-0000
490 Trying spool file TESTSUITE/spool//input//10HmaY-000000005vi-0000-D
491 reading spool file 10HmaY-000000005vi-0000-H
492 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=x@y
493 sender_fullhost = (test) [V4NET.11.12.13]
494 sender_rcvhost = [V4NET.11.12.13] (helo=test ident=CALLER)
495 sender_local=0 ident=CALLER
496 Non-recipients:
497  Empty Tree
498 ---- End of tree ----
499 recipients_count=1
500 **** SPOOL_IN - No additional fields
501 body_linecount=1 message_linecount=8
502 DSN: set orcpt:   flags: 0x0
503 Delivery address list:
504   2@b 
505 no retry data available
506 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
507 Considering: 2@b
508 unique = 2@b
509 checking router retry status
510  no   domain  retry record
511  no   address retry record
512 2@b: queued for routing
513 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
514 routing 2@b
515 --------> r1 router <--------
516 local_part=2 domain=b
517 calling r1 router
518 r1 router called for 2@b
519   domain = b
520 set transport t1
521 queued for t1 transport: local_part = 2
522 domain = b
523   errors_to=NULL
524   domain_data=NULL local_part_data=NULL
525 routed by r1 router
526   envelope to: 2@b
527   transport: t1
528 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
529 After routing:
530   Local deliveries:
531     2@b
532   Remote deliveries:
533   Failed addresses:
534   Deferred addresses:
535 search_tidyup called
536 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
537 --------> 2@b <--------
538 no retry data available
539 search_tidyup called
540 changed uid/gid: local delivery to 2 <2@b> transport=t1
541   uid=CALLER_UID gid=CALLER_GID pid=p1239
542   home=NULL current=/
543 set_process_info: pppp delivering 10HmaY-000000005vi-0000 to 2 using t1
544 appendfile transport entered
545 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
546   file=TESTSUITE/test-mail/2 format=unix
547   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
548   message_suffix=\n
549   maildir_use_size_file=no
550   locking by lockfile fcntl 
551 below-home: de-tainting path 'TESTSUITE/test-mail/2'
552 lock name: TESTSUITE/test-mail/2.lock
553 hitch name: TESTSUITE/test-mail/2.lock.test.ex.dddddddd.pppppppp
554 lock file created
555 mailbox TESTSUITE/test-mail/2 is locked
556 writing to file TESTSUITE/test-mail/2
557 writing data block fd=dddd size=sss timeout=0
558 cannot use sendfile for body: spoolfile not wireformat
559 writing data block fd=dddd size=sss timeout=0
560 writing data block fd=dddd size=sss timeout=0
561 appendfile yields 0 with errno=dd more_errno=dd
562 search_tidyup called
563 >>>>>>>>>>>>>>>> Exim pid=p1239 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>>
564 journalling 2@b
565 t1 transport returned OK for 2@b
566 post-process 2@b (0)
567 2@b delivered
568 LOG: MAIN
569   => 2 <2@b> R=r1 T=t1
570 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
571 changed uid/gid: post-delivery tidying
572   uid=EXIM_UID gid=EXIM_GID pid=p1238
573 set_process_info: pppp tidying up after delivering 10HmaY-000000005vi-0000
574 Processing retry items
575  Succeeded addresses:
576   2@b: no retry items
577  Failed addresses:
578  Deferred addresses:
579 end of retry processing
580 DSN: processing router : r1
581 DSN: processing successful delivery address: 2@b
582 DSN: Sender_address: x@y
583 DSN: orcpt: NULL  flags: 0x0
584 DSN: envid: NULL  ret: 0
585 DSN: Final recipient: 2@b
586 DSN: Remote SMTP server supports DSN: 0
587 DSN: not sending DSN success message
588 LOG: MAIN
589   Completed
590 end delivery of 10HmaY-000000005vi-0000
591 search_tidyup called
592 search_tidyup called
593 >>>>>>>>>>>>>>>> Exim pid=p1238 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
594 smtp_setup_msg entered
595 SMTP<< quit
596 SMTP>> 221 myhost.test.ex closing connection
597 LOG: smtp_connection MAIN
598   SMTP connection from CALLER D=qqs closed by QUIT
599 search_tidyup called
600 >>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>