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