taint: allow appendfile create_file option to specify a de-tainting safe path
[exim.git] / test / stderr / 5005
1 Exim version x.yz ....
2 changed uid/gid: forcing real = effective
3   uid=uuuu gid=CALLER_GID pid=pppp
4 configuration file is TESTSUITE/test-config
5 admin user
6 changed uid/gid: privilege not needed
7   uid=EXIM_UID gid=EXIM_GID pid=pppp
8 seeking password data for user "CALLER": cache not available
9 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
10 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
11 sender address = CALLER@test.ex
12 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
13 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
14 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
15 Sender: CALLER@test.ex
16 Recipients:
17   nofile@test.ex
18 search_tidyup called
19 >>Headers received:
20
21 rewrite_one_header: type=F:
22   From: CALLER_NAME <CALLER@test.ex>
23 search_tidyup called
24 >>Headers after rewriting and local additions:
25 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
26 F From: CALLER_NAME <CALLER@test.ex>
27   Date: Tue, 2 Mar 1999 09:44:33 +0000
28
29 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
30 Data file written for message 10HmaX-0005vi-00
31 >>Generated Received: header line
32 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
33         (envelope-from <CALLER@test.ex>)
34         id 10HmaX-0005vi-00
35         for nofile@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
36 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
37 DSN: **** SPOOL_OUT - address: <nofile@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
38 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
39 Size of headers = sss
40 LOG: MAIN
41   <= CALLER@test.ex U=CALLER P=local S=sss
42 created log directory TESTSUITE/spool/log
43 search_tidyup called
44 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-0005vi-00
45 Exim version x.yz ....
46 changed uid/gid: forcing real = effective
47   uid=uuuu gid=EXIM_GID pid=pppp
48 configuration file is TESTSUITE/test-config
49 trusted user
50 admin user
51 dropping to exim gid; retaining priv uid
52 seeking password data for user "CALLER": cache not available
53 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
54 set_process_info: pppp delivering specified messages
55 set_process_info: pppp delivering 10HmaX-0005vi-00
56 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
57 reading spool file 10HmaX-0005vi-00-H
58 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
59 sender_local=1 ident=CALLER
60 Non-recipients:
61  Empty Tree
62 ---- End of tree ----
63 recipients_count=1
64 **** SPOOL_IN - No additional fields
65 body_linecount=1 message_linecount=7
66 DSN: set orcpt:   flags: 0x0
67 Delivery address list:
68   nofile@test.ex 
69  locking TESTSUITE/spool/db/retry.lockfile
70  locked  TESTSUITE/spool/db/retry.lockfile
71  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
72  returned from EXIM_DBOPEN: (nil)
73  ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
74  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
75 no retry data available
76 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
77 Considering: nofile@test.ex
78 unique = nofile@test.ex
79 no   domain  retry record
80 no   address retry record
81 nofile@test.ex: queued for routing
82 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
83 routing nofile@test.ex
84 --------> r1 router <--------
85 local_part=nofile domain=test.ex
86 calling r1 router
87 r1 router called for nofile@test.ex
88   domain = test.ex
89 set transport t1
90 queued for t1 transport: local_part = nofile
91 domain = test.ex
92   errors_to=NULL
93   domain_data=NULL local_part_data=NULL
94 routed by r1 router
95   envelope to: nofile@test.ex
96   transport: t1
97 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
98 After routing:
99   Local deliveries:
100     nofile@test.ex
101   Remote deliveries:
102   Failed addresses:
103   Deferred addresses:
104 search_tidyup called
105 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
106 --------> nofile@test.ex <--------
107  locking TESTSUITE/spool/db/retry.lockfile
108  locked  TESTSUITE/spool/db/retry.lockfile
109  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
110  returned from EXIM_DBOPEN: (nil)
111  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
112 no retry data available
113 search_tidyup called
114 changed uid/gid: local delivery to nofile <nofile@test.ex> transport=t1
115   uid=CALLER_UID gid=CALLER_GID pid=pppp
116   home=NULL current=/
117 set_process_info: pppp delivering 10HmaX-0005vi-00 to nofile using t1
118 appendfile transport entered
119 appendfile: mode=600 notify_comsat=0 quota=500 warning=0
120   directory=TESTSUITE/test-mail/nofile format=maildir
121   message_prefix=null
122   message_suffix=null
123   maildir_use_size_file=yes
124 de-tainting path 'TESTSUITE/test-mail/nofile'
125 ensuring maildir directories exist in TESTSUITE/test-mail/nofile
126 created directory TESTSUITE/test-mail/nofile
127 created directory TESTSUITE/test-mail/nofile/tmp
128 created directory TESTSUITE/test-mail/nofile/new
129 created directory TESTSUITE/test-mail/nofile/cur
130 using regex for maildir directory selection: ^(?:cur|new|\..*)$
131 looking for maildirsize in TESTSUITE/test-mail/nofile
132 TESTSUITE/test-mail/nofile/maildirsize does not exist: recalculating
133 MUNGED: the check_dir_size lines have been sorted to ensure consistency
134 check_dir_size: dir=TESTSUITE/test-mail/nofile/cur sum=0 count=dd
135 check_dir_size: dir=TESTSUITE/test-mail/nofile/new sum=0 count=dd
136 skipping TESTSUITE/test-mail/nofile/tmp: dir_regex does not match
137 maildir_compute_size: path=TESTSUITE/test-mail/nofile
138   sum=0 filecount=0 timestamp=ddddddd
139 checking subdirectory timestamps
140 MUNGED: the check_dir_size lines have been sorted to ensure consistency
141 skipping TESTSUITE/test-mail/nofile/maildirsize: dir_regex does not match
142 skipping TESTSUITE/test-mail/nofile/tmp: dir_regex does not match
143 maildir_compute_size (timestamp_only): ddddddd
144 returning maildir size=sss filecount=0
145 delivering in maildir format in TESTSUITE/test-mail/nofile
146 writing to tmp/MAILDIR.myhost.test.ex
147 Exim quota = 500 old size = sssss this message = sss (included)
148   file count quota = 0 count = 0
149 cannot use sendfile for body: spoolfile not wireformat
150 writing data block fd=dddd size=sss timeout=0
151 added 'ddd 1' to maildirsize file
152 renaming temporary file
153 renamed tmp/MAILDIR.myhost.test.ex as new/MAILDIR.myhost.test.ex
154 appendfile yields 0 with errno=dd more_errno=dd
155 search_tidyup called
156 journalling nofile@test.ex
157 t1 transport returned OK for nofile@test.ex
158 post-process nofile@test.ex (0)
159 nofile@test.ex delivered
160 LOG: MAIN
161   => nofile <nofile@test.ex> R=r1 T=t1
162 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
163 changed uid/gid: post-delivery tidying
164   uid=EXIM_UID gid=EXIM_GID pid=pppp
165 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
166 Processing retry items
167 Succeeded addresses:
168  nofile@test.ex: no retry items
169 Failed addresses:
170 Deferred addresses:
171 end of retry processing
172 DSN: processing router : r1
173 DSN: processing successful delivery address: nofile@test.ex
174 DSN: Sender_address: CALLER@test.ex
175 DSN: orcpt: NULL  flags: 0x0
176 DSN: envid: NULL  ret: 0
177 DSN: Final recipient: nofile@test.ex
178 DSN: Remote SMTP server supports DSN: 0
179 DSN: not sending DSN success message
180 LOG: MAIN
181   Completed
182 end delivery of 10HmaX-0005vi-00
183 search_tidyup called
184 search_tidyup called
185 >>>>>>>>>>>>>>>> Exim pid=pppp (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
186 search_tidyup called
187 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
188 Exim version x.yz ....
189 changed uid/gid: forcing real = effective
190   uid=uuuu gid=CALLER_GID pid=pppp
191 configuration file is TESTSUITE/test-config
192 admin user
193 changed uid/gid: privilege not needed
194   uid=EXIM_UID gid=EXIM_GID pid=pppp
195 seeking password data for user "CALLER": cache not available
196 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
197 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
198 sender address = CALLER@test.ex
199 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
200 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
201 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
202 Sender: CALLER@test.ex
203 Recipients:
204   userx@test.ex
205 search_tidyup called
206 >>Headers received:
207
208 rewrite_one_header: type=F:
209   From: CALLER_NAME <CALLER@test.ex>
210 search_tidyup called
211 >>Headers after rewriting and local additions:
212 I Message-Id: <E10HmaY-0005vi-00@myhost.test.ex>
213 F From: CALLER_NAME <CALLER@test.ex>
214   Date: Tue, 2 Mar 1999 09:44:33 +0000
215
216 Data file name: TESTSUITE/spool//input//10HmaY-0005vi-00-D
217 Data file written for message 10HmaY-0005vi-00
218 >>Generated Received: header line
219 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
220         (envelope-from <CALLER@test.ex>)
221         id 10HmaY-0005vi-00
222         for userx@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
223 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaY-0005vi-00
224 DSN: **** SPOOL_OUT - address: <userx@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
225 Renaming spool header file: TESTSUITE/spool//input//10HmaY-0005vi-00-H
226 Size of headers = sss
227 LOG: MAIN
228   <= CALLER@test.ex U=CALLER P=local S=sss
229 search_tidyup called
230 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaY-0005vi-00
231 Exim version x.yz ....
232 changed uid/gid: forcing real = effective
233   uid=uuuu gid=EXIM_GID pid=pppp
234 configuration file is TESTSUITE/test-config
235 trusted user
236 admin user
237 dropping to exim gid; retaining priv uid
238 seeking password data for user "CALLER": cache not available
239 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
240 set_process_info: pppp delivering specified messages
241 set_process_info: pppp delivering 10HmaY-0005vi-00
242 Trying spool file TESTSUITE/spool//input//10HmaY-0005vi-00-D
243 reading spool file 10HmaY-0005vi-00-H
244 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
245 sender_local=1 ident=CALLER
246 Non-recipients:
247  Empty Tree
248 ---- End of tree ----
249 recipients_count=1
250 **** SPOOL_IN - No additional fields
251 body_linecount=1 message_linecount=7
252 DSN: set orcpt:   flags: 0x0
253 Delivery address list:
254   userx@test.ex 
255  locking TESTSUITE/spool/db/retry.lockfile
256  locked  TESTSUITE/spool/db/retry.lockfile
257  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
258  returned from EXIM_DBOPEN: (nil)
259  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
260 no retry data available
261 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
262 Considering: userx@test.ex
263 unique = userx@test.ex
264 no   domain  retry record
265 no   address retry record
266 userx@test.ex: queued for routing
267 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
268 routing userx@test.ex
269 --------> r1 router <--------
270 local_part=userx domain=test.ex
271 calling r1 router
272 r1 router called for userx@test.ex
273   domain = test.ex
274 set transport t1
275 queued for t1 transport: local_part = userx
276 domain = test.ex
277   errors_to=NULL
278   domain_data=NULL local_part_data=NULL
279 routed by r1 router
280   envelope to: userx@test.ex
281   transport: t1
282 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
283 After routing:
284   Local deliveries:
285     userx@test.ex
286   Remote deliveries:
287   Failed addresses:
288   Deferred addresses:
289 search_tidyup called
290 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
291 --------> userx@test.ex <--------
292  locking TESTSUITE/spool/db/retry.lockfile
293  locked  TESTSUITE/spool/db/retry.lockfile
294  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
295  returned from EXIM_DBOPEN: (nil)
296  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
297 no retry data available
298 search_tidyup called
299 changed uid/gid: local delivery to userx <userx@test.ex> transport=t1
300   uid=CALLER_UID gid=CALLER_GID pid=pppp
301   home=NULL current=/
302 set_process_info: pppp delivering 10HmaY-0005vi-00 to userx using t1
303 appendfile transport entered
304 appendfile: mode=600 notify_comsat=0 quota=500 warning=0
305   directory=TESTSUITE/test-mail/userx format=maildir
306   message_prefix=null
307   message_suffix=null
308   maildir_use_size_file=yes
309 de-tainting path 'TESTSUITE/test-mail/userx'
310 ensuring maildir directories exist in TESTSUITE/test-mail/userx
311 created directory TESTSUITE/test-mail/userx/tmp
312 created directory TESTSUITE/test-mail/userx/new
313 created directory TESTSUITE/test-mail/userx/cur
314 using regex for maildir directory selection: ^(?:cur|new|\..*)$
315 looking for maildirsize in TESTSUITE/test-mail/userx
316 reading quota parameters from maildirsize data
317 cached quota is out of date: recalculating
318   quota=500 cached_quota=50 filecount_quota=0 cached_quota_filecount=2
319 MUNGED: the check_dir_size lines have been sorted to ensure consistency
320 check_dir_size: dir=TESTSUITE/test-mail/userx/cur sum=0 count=dd
321 check_dir_size: dir=TESTSUITE/test-mail/userx/new sum=0 count=dd
322 skipping TESTSUITE/test-mail/userx/maildirsize: dir_regex does not match
323 skipping TESTSUITE/test-mail/userx/tmp: dir_regex does not match
324 maildir_compute_size: path=TESTSUITE/test-mail/userx
325   sum=0 filecount=0 timestamp=ddddddd
326 checking subdirectory timestamps
327 MUNGED: the check_dir_size lines have been sorted to ensure consistency
328 skipping TESTSUITE/test-mail/userx/maildirsize: dir_regex does not match
329 skipping TESTSUITE/test-mail/userx/tmp: dir_regex does not match
330 maildir_compute_size (timestamp_only): ddddddd
331 returning maildir size=sss filecount=0
332 delivering in maildir format in TESTSUITE/test-mail/userx
333 writing to tmp/MAILDIR.myhost.test.ex
334 Exim quota = 500 old size = sssss this message = sss (included)
335   file count quota = 0 count = 0
336 cannot use sendfile for body: spoolfile not wireformat
337 writing data block fd=dddd size=sss timeout=0
338 added 'ddd 1' to maildirsize file
339 renaming temporary file
340 renamed tmp/MAILDIR.myhost.test.ex as new/MAILDIR.myhost.test.ex
341 appendfile yields 0 with errno=dd more_errno=dd
342 search_tidyup called
343 journalling userx@test.ex
344 t1 transport returned OK for userx@test.ex
345 post-process userx@test.ex (0)
346 userx@test.ex delivered
347 LOG: MAIN
348   => userx <userx@test.ex> R=r1 T=t1
349 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
350 changed uid/gid: post-delivery tidying
351   uid=EXIM_UID gid=EXIM_GID pid=pppp
352 set_process_info: pppp tidying up after delivering 10HmaY-0005vi-00
353 Processing retry items
354 Succeeded addresses:
355  userx@test.ex: no retry items
356 Failed addresses:
357 Deferred addresses:
358 end of retry processing
359 DSN: processing router : r1
360 DSN: processing successful delivery address: userx@test.ex
361 DSN: Sender_address: CALLER@test.ex
362 DSN: orcpt: NULL  flags: 0x0
363 DSN: envid: NULL  ret: 0
364 DSN: Final recipient: userx@test.ex
365 DSN: Remote SMTP server supports DSN: 0
366 DSN: not sending DSN success message
367 LOG: MAIN
368   Completed
369 end delivery of 10HmaY-0005vi-00
370 search_tidyup called
371 search_tidyup called
372 >>>>>>>>>>>>>>>> Exim pid=pppp (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
373 search_tidyup called
374 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
375 Exim version x.yz ....
376 changed uid/gid: forcing real = effective
377   uid=uuuu gid=CALLER_GID pid=pppp
378 configuration file is TESTSUITE/test-config
379 admin user
380 changed uid/gid: privilege not needed
381   uid=EXIM_UID gid=EXIM_GID pid=pppp
382 seeking password data for user "CALLER": cache not available
383 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
384 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
385 sender address = CALLER@test.ex
386 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
387 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
388 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
389 Sender: CALLER@test.ex
390 Recipients:
391   userx@test.ex
392 search_tidyup called
393 >>Headers received:
394
395 rewrite_one_header: type=F:
396   From: CALLER_NAME <CALLER@test.ex>
397 search_tidyup called
398 >>Headers after rewriting and local additions:
399 I Message-Id: <E10HmaZ-0005vi-00@myhost.test.ex>
400 F From: CALLER_NAME <CALLER@test.ex>
401   Date: Tue, 2 Mar 1999 09:44:33 +0000
402
403 Data file name: TESTSUITE/spool//input//10HmaZ-0005vi-00-D
404 Data file written for message 10HmaZ-0005vi-00
405 >>Generated Received: header line
406 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
407         (envelope-from <CALLER@test.ex>)
408         id 10HmaZ-0005vi-00
409         for userx@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
410 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaZ-0005vi-00
411 DSN: **** SPOOL_OUT - address: <userx@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
412 Renaming spool header file: TESTSUITE/spool//input//10HmaZ-0005vi-00-H
413 Size of headers = sss
414 LOG: MAIN
415   <= CALLER@test.ex U=CALLER P=local S=sss
416 search_tidyup called
417 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaZ-0005vi-00
418 Exim version x.yz ....
419 changed uid/gid: forcing real = effective
420   uid=uuuu gid=EXIM_GID pid=pppp
421 configuration file is TESTSUITE/test-config
422 trusted user
423 admin user
424 dropping to exim gid; retaining priv uid
425 seeking password data for user "CALLER": cache not available
426 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
427 set_process_info: pppp delivering specified messages
428 set_process_info: pppp delivering 10HmaZ-0005vi-00
429 Trying spool file TESTSUITE/spool//input//10HmaZ-0005vi-00-D
430 reading spool file 10HmaZ-0005vi-00-H
431 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
432 sender_local=1 ident=CALLER
433 Non-recipients:
434  Empty Tree
435 ---- End of tree ----
436 recipients_count=1
437 **** SPOOL_IN - No additional fields
438 body_linecount=1 message_linecount=7
439 DSN: set orcpt:   flags: 0x0
440 Delivery address list:
441   userx@test.ex 
442  locking TESTSUITE/spool/db/retry.lockfile
443  locked  TESTSUITE/spool/db/retry.lockfile
444  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
445  returned from EXIM_DBOPEN: (nil)
446  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
447 no retry data available
448 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
449 Considering: userx@test.ex
450 unique = userx@test.ex
451 no   domain  retry record
452 no   address retry record
453 userx@test.ex: queued for routing
454 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
455 routing userx@test.ex
456 --------> r1 router <--------
457 local_part=userx domain=test.ex
458 calling r1 router
459 r1 router called for userx@test.ex
460   domain = test.ex
461 set transport t1
462 queued for t1 transport: local_part = userx
463 domain = test.ex
464   errors_to=NULL
465   domain_data=NULL local_part_data=NULL
466 routed by r1 router
467   envelope to: userx@test.ex
468   transport: t1
469 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
470 After routing:
471   Local deliveries:
472     userx@test.ex
473   Remote deliveries:
474   Failed addresses:
475   Deferred addresses:
476 search_tidyup called
477 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
478 --------> userx@test.ex <--------
479  locking TESTSUITE/spool/db/retry.lockfile
480  locked  TESTSUITE/spool/db/retry.lockfile
481  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
482  returned from EXIM_DBOPEN: (nil)
483  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
484 no retry data available
485 search_tidyup called
486 changed uid/gid: local delivery to userx <userx@test.ex> transport=t1
487   uid=CALLER_UID gid=CALLER_GID pid=pppp
488   home=NULL current=/
489 set_process_info: pppp delivering 10HmaZ-0005vi-00 to userx using t1
490 appendfile transport entered
491 appendfile: mode=600 notify_comsat=0 quota=500 warning=0
492   directory=TESTSUITE/test-mail/userx format=maildir
493   message_prefix=null
494   message_suffix=null
495   maildir_use_size_file=yes
496 de-tainting path 'TESTSUITE/test-mail/userx'
497 ensuring maildir directories exist in TESTSUITE/test-mail/userx
498 using regex for maildir directory selection: ^(?:cur|new|\..*)$
499 looking for maildirsize in TESTSUITE/test-mail/userx
500 reading quota parameters from maildirsize data
501 computing maildir size from maildirsize data
502 over quota and maildirsize has more than 1 entry: recalculating
503 MUNGED: the check_dir_size lines have been sorted to ensure consistency
504 check_dir_size: dir=TESTSUITE/test-mail/userx/cur sum=0 count=dd
505 check_dir_size: dir=TESTSUITE/test-mail/userx/new sum=dddd count=dd
506 skipping TESTSUITE/test-mail/userx/maildirsize: dir_regex does not match
507 skipping TESTSUITE/test-mail/userx/tmp: dir_regex does not match
508 maildir_compute_size: path=TESTSUITE/test-mail/userx
509   sum=dddd filecount=1 timestamp=ddddddd
510 checking subdirectory timestamps
511 MUNGED: the check_dir_size lines have been sorted to ensure consistency
512 skipping TESTSUITE/test-mail/userx/maildirsize: dir_regex does not match
513 skipping TESTSUITE/test-mail/userx/tmp: dir_regex does not match
514 maildir_compute_size (timestamp_only): ddddddd
515 returning maildir size=sss filecount=1
516 delivering in maildir format in TESTSUITE/test-mail/userx
517 writing to tmp/MAILDIR.myhost.test.ex
518 Exim quota = 500 old size = sssss this message = sss (included)
519   file count quota = 0 count = 1
520 mailbox quota exceeded
521 maildir: time since "new" directory modified = 10s
522 Exim quota exceeded for tmp/dddddddddd.HddddddPddddd.myhost.test.ex
523 appendfile yields 1 with errno=-22 more_errno=dd
524 search_tidyup called
525 t1 transport returned DEFER for userx@test.ex
526 added retry item for T:userx@test.ex: errno=-22 more_errno=dd flags=0
527 post-process userx@test.ex (1)
528 LOG: MAIN
529   == userx@test.ex R=r1 T=t1 defer (-22): mailbox is full (MTA-imposed quota exceeded while writing to tmp/MAILDIR.myhost.test.ex)
530 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
531 changed uid/gid: post-delivery tidying
532   uid=EXIM_UID gid=EXIM_GID pid=pppp
533 set_process_info: pppp tidying up after delivering 10HmaZ-0005vi-00
534 Processing retry items
535 Succeeded addresses:
536 Failed addresses:
537 Deferred addresses:
538  userx@test.ex
539  locking TESTSUITE/spool/db/retry.lockfile
540  locked  TESTSUITE/spool/db/retry.lockfile
541  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDWR
542  returned from EXIM_DBOPEN: 0xAAAAAAAA
543  opened hints database TESTSUITE/spool/db/retry: flags=O_RDWR
544 address match test: subject=userx@test.ex pattern=*
545 test.ex in "*"? yes (matched "*")
546 userx@test.ex in "*"? yes (matched "*")
547 retry for T:userx@test.ex = * 0 0
548  dbfn_read: key=T:userx@test.ex
549 failing_interval=ttt message_age=ttt
550 Writing retry data for T:userx@test.ex
551   first failed=dddd last try=dddd next try=+86400 expired=0
552   errno=-22 more_errno=dd mailbox is full (MTA-imposed quota exceeded while writing to tmp/MAILDIR.myhost.test.ex)
553  dbfn_write: key=T:userx@test.ex
554  EXIM_DBCLOSE(0xAAAAAAAA)
555  closed hints database and lockfile
556 end of retry processing
557 delivery deferred: update_spool=1 header_rewritten=0
558 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaZ-0005vi-00
559 DSN: **** SPOOL_OUT - address: <userx@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
560 Renaming spool header file: TESTSUITE/spool//input//10HmaZ-0005vi-00-H
561 Size of headers = sss
562 end delivery of 10HmaZ-0005vi-00
563 search_tidyup called
564 search_tidyup called
565 >>>>>>>>>>>>>>>> Exim pid=pppp (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
566 search_tidyup called
567 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
568 Exim version x.yz ....
569 changed uid/gid: forcing real = effective
570   uid=uuuu gid=CALLER_GID pid=pppp
571 configuration file is TESTSUITE/test-config
572 admin user
573 changed uid/gid: privilege not needed
574   uid=EXIM_UID gid=EXIM_GID pid=pppp
575 seeking password data for user "CALLER": cache not available
576 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
577 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
578 sender address = CALLER@test.ex
579 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
580 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
581 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
582 Sender: CALLER@test.ex
583 Recipients:
584   userx@test.ex
585 search_tidyup called
586 >>Headers received:
587
588 rewrite_one_header: type=F:
589   From: CALLER_NAME <CALLER@test.ex>
590 search_tidyup called
591 >>Headers after rewriting and local additions:
592 I Message-Id: <E10HmbA-0005vi-00@myhost.test.ex>
593 F From: CALLER_NAME <CALLER@test.ex>
594   Date: Tue, 2 Mar 1999 09:44:33 +0000
595
596 Data file name: TESTSUITE/spool//input//10HmbA-0005vi-00-D
597 Data file written for message 10HmbA-0005vi-00
598 >>Generated Received: header line
599 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
600         (envelope-from <CALLER@test.ex>)
601         id 10HmbA-0005vi-00
602         for userx@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
603 Writing spool header file: TESTSUITE/spool//input//hdr.10HmbA-0005vi-00
604 DSN: **** SPOOL_OUT - address: <userx@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
605 Renaming spool header file: TESTSUITE/spool//input//10HmbA-0005vi-00-H
606 Size of headers = sss
607 LOG: MAIN
608   <= CALLER@test.ex U=CALLER P=local S=sss
609 search_tidyup called
610 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmbA-0005vi-00
611 Exim version x.yz ....
612 changed uid/gid: forcing real = effective
613   uid=uuuu gid=EXIM_GID pid=pppp
614 configuration file is TESTSUITE/test-config
615 trusted user
616 admin user
617 dropping to exim gid; retaining priv uid
618 seeking password data for user "CALLER": cache not available
619 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
620 set_process_info: pppp delivering specified messages
621 set_process_info: pppp delivering 10HmbA-0005vi-00
622 Trying spool file TESTSUITE/spool//input//10HmbA-0005vi-00-D
623 reading spool file 10HmbA-0005vi-00-H
624 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
625 sender_local=1 ident=CALLER
626 Non-recipients:
627  Empty Tree
628 ---- End of tree ----
629 recipients_count=1
630 **** SPOOL_IN - No additional fields
631 body_linecount=1 message_linecount=7
632 DSN: set orcpt:   flags: 0x0
633 Delivery address list:
634   userx@test.ex 
635  locking TESTSUITE/spool/db/retry.lockfile
636  locked  TESTSUITE/spool/db/retry.lockfile
637  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
638  returned from EXIM_DBOPEN: 0xAAAAAAAA
639  opened hints database TESTSUITE/spool/db/retry: flags=O_RDONLY
640 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
641 Considering: userx@test.ex
642 unique = userx@test.ex
643  dbfn_read: key=R:test.ex
644  dbfn_read: key=R:userx@test.ex
645  dbfn_read: key=R:userx@test.ex:<CALLER@test.ex>
646 no   domain  retry record
647 no   address retry record
648 userx@test.ex: queued for routing
649  EXIM_DBCLOSE(0xAAAAAAAA)
650  closed hints database and lockfile
651 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
652 routing userx@test.ex
653 --------> r1 router <--------
654 local_part=userx domain=test.ex
655 calling r1 router
656 r1 router called for userx@test.ex
657   domain = test.ex
658 set transport t1
659 queued for t1 transport: local_part = userx
660 domain = test.ex
661   errors_to=NULL
662   domain_data=NULL local_part_data=NULL
663 routed by r1 router
664   envelope to: userx@test.ex
665   transport: t1
666 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
667 After routing:
668   Local deliveries:
669     userx@test.ex
670   Remote deliveries:
671   Failed addresses:
672   Deferred addresses:
673 search_tidyup called
674 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
675 --------> userx@test.ex <--------
676  locking TESTSUITE/spool/db/retry.lockfile
677  locked  TESTSUITE/spool/db/retry.lockfile
678  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
679  returned from EXIM_DBOPEN: 0xAAAAAAAA
680  opened hints database TESTSUITE/spool/db/retry: flags=O_RDONLY
681  dbfn_read: key=T:userx@test.ex
682 retry record exists: age=ttt (max 1w)
683   time to retry = tttt expired = 0
684  EXIM_DBCLOSE(0xAAAAAAAA)
685  closed hints database and lockfile
686 search_tidyup called
687 changed uid/gid: local delivery to userx <userx@test.ex> transport=t1
688   uid=CALLER_UID gid=CALLER_GID pid=pppp
689   home=NULL current=/
690 set_process_info: pppp delivering 10HmbA-0005vi-00 to userx using t1
691 appendfile transport entered
692 appendfile: mode=600 notify_comsat=0 quota=500 warning=0
693   directory=TESTSUITE/test-mail/userx format=maildir
694   message_prefix=null
695   message_suffix=null
696   maildir_use_size_file=yes
697 de-tainting path 'TESTSUITE/test-mail/userx'
698 ensuring maildir directories exist in TESTSUITE/test-mail/userx
699 using regex for maildir directory selection: ^(?:cur|new|\..*)$
700 looking for maildirsize in TESTSUITE/test-mail/userx
701 reading quota parameters from maildirsize data
702 computing maildir size from maildirsize data
703 returning maildir size=sss filecount=1
704 delivering in maildir format in TESTSUITE/test-mail/userx
705 writing to tmp/MAILDIR.myhost.test.ex
706 Exim quota = 500 old size = sssss this message = sss (included)
707   file count quota = 0 count = 1
708 mailbox quota exceeded
709 maildir: time since "new" directory modified = 10s
710 Exim quota exceeded for tmp/dddddddddd.HddddddPddddd.myhost.test.ex
711 appendfile yields 1 with errno=-22 more_errno=dd
712 search_tidyup called
713 t1 transport returned DEFER for userx@test.ex
714 added retry item for T:userx@test.ex: errno=-22 more_errno=dd flags=0
715 post-process userx@test.ex (1)
716 LOG: MAIN
717   == userx@test.ex R=r1 T=t1 defer (-22): mailbox is full (MTA-imposed quota exceeded while writing to tmp/MAILDIR.myhost.test.ex)
718 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
719 changed uid/gid: post-delivery tidying
720   uid=EXIM_UID gid=EXIM_GID pid=pppp
721 set_process_info: pppp tidying up after delivering 10HmbA-0005vi-00
722 Processing retry items
723 Succeeded addresses:
724 Failed addresses:
725 Deferred addresses:
726  userx@test.ex
727  locking TESTSUITE/spool/db/retry.lockfile
728  locked  TESTSUITE/spool/db/retry.lockfile
729  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDWR
730  returned from EXIM_DBOPEN: 0xAAAAAAAA
731  opened hints database TESTSUITE/spool/db/retry: flags=O_RDWR
732 address match test: subject=userx@test.ex pattern=*
733 test.ex in "*"? yes (matched "*")
734 userx@test.ex in "*"? yes (matched "*")
735 retry for T:userx@test.ex = * 0 0
736  dbfn_read: key=T:userx@test.ex
737 failing_interval=ttt message_age=ttt
738 Writing retry data for T:userx@test.ex
739   first failed=dddd last try=dddd next try=+86400 expired=0
740   errno=-22 more_errno=dd mailbox is full (MTA-imposed quota exceeded while writing to tmp/MAILDIR.myhost.test.ex)
741  dbfn_write: key=T:userx@test.ex
742  EXIM_DBCLOSE(0xAAAAAAAA)
743  closed hints database and lockfile
744 end of retry processing
745 delivery deferred: update_spool=1 header_rewritten=0
746 Writing spool header file: TESTSUITE/spool//input//hdr.10HmbA-0005vi-00
747 DSN: **** SPOOL_OUT - address: <userx@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
748 Renaming spool header file: TESTSUITE/spool//input//10HmbA-0005vi-00-H
749 Size of headers = sss
750 end delivery of 10HmbA-0005vi-00
751 search_tidyup called
752 search_tidyup called
753 >>>>>>>>>>>>>>>> Exim pid=pppp (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
754 search_tidyup called
755 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>