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