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