DKIM: error verification on missing tags. Bug 1853
[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 DSN: r1 propagating DSN
9 seeking password data for user "CALLER": cache not available
10 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
11 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
12 sender address = CALLER@test.ex
13 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
14 Sender: CALLER@test.ex
15 Recipients:
16   nofile@test.ex
17 search_tidyup called
18 >>Headers received:
19
20 rewrite_one_header: type=F:
21   From: CALLER_NAME <CALLER@test.ex>
22 search_tidyup called
23 >>Headers after rewriting and local additions:
24 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
25 F From: CALLER_NAME <CALLER@test.ex>
26   Date: Tue, 2 Mar 1999 09:44:33 +0000
27
28 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
29 Data file written for message 10HmaX-0005vi-00
30 >>Generated Received: header line
31 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
32         (envelope-from <CALLER@test.ex>)
33         id 10HmaX-0005vi-00
34         for nofile@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
35 calling local_scan(); timeout=300
36 local_scan() returned 0 NULL
37 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
38 DSN: Write SPOOL :-dsn_envid NULL
39 DSN: Write SPOOL :-dsn_ret 0
40 DSN: Flags :0
41 DSN: **** SPOOL_OUT - address: |nofile@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
42 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
43 Size of headers = sss
44 LOG: MAIN
45   <= CALLER@test.ex U=CALLER P=local S=sss
46 created log directory TESTSUITE/spool/log
47 search_tidyup called
48 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmaX-0005vi-00
49 Exim version x.yz ....
50 changed uid/gid: forcing real = effective
51   uid=uuuu gid=EXIM_GID pid=pppp
52 configuration file is TESTSUITE/test-config
53 trusted user
54 admin user
55 DSN: r1 propagating DSN
56 seeking password data for user "CALLER": cache not available
57 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
58 set_process_info: pppp delivering specified messages
59 set_process_info: pppp delivering 10HmaX-0005vi-00
60 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
61 reading spool file 10HmaX-0005vi-00-H
62 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
63 sender_local=1 ident=CALLER
64 Non-recipients:
65 Empty Tree
66 ---- End of tree ----
67 recipients_count=1
68 **** SPOOL_IN - No additional fields
69 body_linecount=1 message_linecount=7
70 DSN: set orcpt: NULL  flags: 0
71 Delivery address list:
72   nofile@test.ex 
73 locking TESTSUITE/spool/db/retry.lockfile
74 locked TESTSUITE/spool/db/retry.lockfile
75 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
76 returned from EXIM_DBOPEN
77 ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
78 no retry data available
79 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
80 Considering: nofile@test.ex
81 unique = nofile@test.ex
82 no domain retry record
83 no address retry record
84 nofile@test.ex: queued for routing
85 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
86 routing nofile@test.ex
87 --------> r1 router <--------
88 local_part=nofile domain=test.ex
89 calling r1 router
90 r1 router called for nofile@test.ex
91   domain = test.ex
92 set transport t1
93 queued for t1 transport: local_part = nofile
94 domain = test.ex
95   errors_to=NULL
96   domain_data=NULL localpart_data=NULL
97 routed by r1 router
98   envelope to: nofile@test.ex
99   transport: t1
100 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
101 After routing:
102   Local deliveries:
103     nofile@test.ex
104   Remote deliveries:
105   Failed addresses:
106   Deferred addresses:
107 search_tidyup called
108 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
109 --------> nofile@test.ex <--------
110 locking TESTSUITE/spool/db/retry.lockfile
111 locked TESTSUITE/spool/db/retry.lockfile
112 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
113 returned from EXIM_DBOPEN
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 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: 0
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 terminating with rc=0 >>>>>>>>>>>>>>>>
186 search_tidyup called
187 >>>>>>>>>>>>>>>> Exim pid=pppp 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 DSN: r1 propagating DSN
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 Sender: CALLER@test.ex
202 Recipients:
203   userx@test.ex
204 search_tidyup called
205 >>Headers received:
206
207 rewrite_one_header: type=F:
208   From: CALLER_NAME <CALLER@test.ex>
209 search_tidyup called
210 >>Headers after rewriting and local additions:
211 I Message-Id: <E10HmaY-0005vi-00@myhost.test.ex>
212 F From: CALLER_NAME <CALLER@test.ex>
213   Date: Tue, 2 Mar 1999 09:44:33 +0000
214
215 Data file name: TESTSUITE/spool//input//10HmaY-0005vi-00-D
216 Data file written for message 10HmaY-0005vi-00
217 >>Generated Received: header line
218 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
219         (envelope-from <CALLER@test.ex>)
220         id 10HmaY-0005vi-00
221         for userx@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
222 calling local_scan(); timeout=300
223 local_scan() returned 0 NULL
224 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
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=0xfbb95cfd -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 DSN: r1 propagating DSN
242 seeking password data for user "CALLER": cache not available
243 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
244 set_process_info: pppp delivering specified messages
245 set_process_info: pppp delivering 10HmaY-0005vi-00
246 Trying spool file TESTSUITE/spool//input//10HmaY-0005vi-00-D
247 reading spool file 10HmaY-0005vi-00-H
248 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
249 sender_local=1 ident=CALLER
250 Non-recipients:
251 Empty Tree
252 ---- End of tree ----
253 recipients_count=1
254 **** SPOOL_IN - No additional fields
255 body_linecount=1 message_linecount=7
256 DSN: set orcpt: NULL  flags: 0
257 Delivery address list:
258   userx@test.ex 
259 locking TESTSUITE/spool/db/retry.lockfile
260 locked TESTSUITE/spool/db/retry.lockfile
261 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
262 returned from EXIM_DBOPEN
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(TESTSUITE/spool/db/retry)
298 returned from EXIM_DBOPEN
299 no retry data available
300 search_tidyup called
301 changed uid/gid: local delivery to userx <userx@test.ex> transport=t1
302   uid=CALLER_UID gid=CALLER_GID pid=pppp
303   home=NULL current=/
304 set_process_info: pppp delivering 10HmaY-0005vi-00 to userx using t1
305 appendfile transport entered
306 appendfile: mode=600 notify_comsat=0 quota=500 warning=0
307   directory=TESTSUITE/test-mail/userx format=maildir
308   message_prefix=null
309   message_suffix=null
310   maildir_use_size_file=yes
311 ensuring maildir directories exist in TESTSUITE/test-mail/userx
312 created directory TESTSUITE/test-mail/userx/tmp
313 created directory TESTSUITE/test-mail/userx/new
314 created directory TESTSUITE/test-mail/userx/cur
315 using regex for maildir directory selection: ^(?:cur|new|\..*)$
316 looking for maildirsize in TESTSUITE/test-mail/userx
317 reading quota parameters from maildirsize data
318 cached quota is out of date: recalculating
319   quota=500 cached_quota=50 filecount_quota=0 cached_quota_filecount=2
320 MUNGED: the check_dir_size lines have been sorted to ensure consistency
321 check_dir_size: dir=TESTSUITE/test-mail/userx/cur sum=0 count=dd
322 check_dir_size: dir=TESTSUITE/test-mail/userx/new sum=0 count=dd
323 skipping TESTSUITE/test-mail/userx/maildirsize: dir_regex does not match
324 skipping TESTSUITE/test-mail/userx/tmp: dir_regex does not match
325 maildir_compute_size: path=TESTSUITE/test-mail/userx
326   sum=0 filecount=0 timestamp=ddddddd
327 checking subdirectory timestamps
328 MUNGED: the check_dir_size lines have been sorted to ensure consistency
329 skipping TESTSUITE/test-mail/userx/maildirsize: dir_regex does not match
330 skipping TESTSUITE/test-mail/userx/tmp: dir_regex does not match
331 maildir_compute_size (timestamp_only): ddddddd
332 returning maildir size=sss filecount=0
333 delivering in maildir format in TESTSUITE/test-mail/userx
334 writing to tmp/MAILDIR.myhost.test.ex
335 Exim quota = 500 old size = sssss this message = sss (included)
336   file count quota = 0 count = 0
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: 0
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 terminating with rc=0 >>>>>>>>>>>>>>>>
373 search_tidyup called
374 >>>>>>>>>>>>>>>> Exim pid=pppp 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 DSN: r1 propagating DSN
383 seeking password data for user "CALLER": cache not available
384 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
385 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
386 sender address = CALLER@test.ex
387 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
388 Sender: CALLER@test.ex
389 Recipients:
390   userx@test.ex
391 search_tidyup called
392 >>Headers received:
393
394 rewrite_one_header: type=F:
395   From: CALLER_NAME <CALLER@test.ex>
396 search_tidyup called
397 >>Headers after rewriting and local additions:
398 I Message-Id: <E10HmaZ-0005vi-00@myhost.test.ex>
399 F From: CALLER_NAME <CALLER@test.ex>
400   Date: Tue, 2 Mar 1999 09:44:33 +0000
401
402 Data file name: TESTSUITE/spool//input//10HmaZ-0005vi-00-D
403 Data file written for message 10HmaZ-0005vi-00
404 >>Generated Received: header line
405 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
406         (envelope-from <CALLER@test.ex>)
407         id 10HmaZ-0005vi-00
408         for userx@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
409 calling local_scan(); timeout=300
410 local_scan() returned 0 NULL
411 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
412 DSN: Write SPOOL :-dsn_envid NULL
413 DSN: Write SPOOL :-dsn_ret 0
414 DSN: Flags :0
415 DSN: **** SPOOL_OUT - address: |userx@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
416 Renaming spool header file: TESTSUITE/spool//input//10HmaZ-0005vi-00-H
417 Size of headers = sss
418 LOG: MAIN
419   <= CALLER@test.ex U=CALLER P=local S=sss
420 search_tidyup called
421 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmaZ-0005vi-00
422 Exim version x.yz ....
423 changed uid/gid: forcing real = effective
424   uid=uuuu gid=EXIM_GID pid=pppp
425 configuration file is TESTSUITE/test-config
426 trusted user
427 admin user
428 DSN: r1 propagating DSN
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: NULL  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(TESTSUITE/spool/db/retry)
449 returned from EXIM_DBOPEN
450 no retry data available
451 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
452 Considering: userx@test.ex
453 unique = userx@test.ex
454 no domain retry record
455 no address retry record
456 userx@test.ex: queued for routing
457 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
458 routing userx@test.ex
459 --------> r1 router <--------
460 local_part=userx domain=test.ex
461 calling r1 router
462 r1 router called for userx@test.ex
463   domain = test.ex
464 set transport t1
465 queued for t1 transport: local_part = userx
466 domain = test.ex
467   errors_to=NULL
468   domain_data=NULL localpart_data=NULL
469 routed by r1 router
470   envelope to: userx@test.ex
471   transport: t1
472 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
473 After routing:
474   Local deliveries:
475     userx@test.ex
476   Remote deliveries:
477   Failed addresses:
478   Deferred addresses:
479 search_tidyup called
480 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
481 --------> userx@test.ex <--------
482 locking TESTSUITE/spool/db/retry.lockfile
483 locked TESTSUITE/spool/db/retry.lockfile
484 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
485 returned from EXIM_DBOPEN
486 no retry data available
487 search_tidyup called
488 changed uid/gid: local delivery to userx <userx@test.ex> transport=t1
489   uid=CALLER_UID gid=CALLER_GID pid=pppp
490   home=NULL current=/
491 set_process_info: pppp delivering 10HmaZ-0005vi-00 to userx using t1
492 appendfile transport entered
493 appendfile: mode=600 notify_comsat=0 quota=500 warning=0
494   directory=TESTSUITE/test-mail/userx format=maildir
495   message_prefix=null
496   message_suffix=null
497   maildir_use_size_file=yes
498 ensuring maildir directories exist in TESTSUITE/test-mail/userx
499 using regex for maildir directory selection: ^(?:cur|new|\..*)$
500 looking for maildirsize in TESTSUITE/test-mail/userx
501 reading quota parameters from maildirsize data
502 computing maildir size from maildirsize data
503 over quota and maildirsize has more than 1 entry: recalculating
504 MUNGED: the check_dir_size lines have been sorted to ensure consistency
505 check_dir_size: dir=TESTSUITE/test-mail/userx/cur sum=0 count=dd
506 check_dir_size: dir=TESTSUITE/test-mail/userx/new sum=dddd count=dd
507 skipping TESTSUITE/test-mail/userx/maildirsize: dir_regex does not match
508 skipping TESTSUITE/test-mail/userx/tmp: dir_regex does not match
509 maildir_compute_size: path=TESTSUITE/test-mail/userx
510   sum=dddd filecount=1 timestamp=ddddddd
511 checking subdirectory timestamps
512 MUNGED: the check_dir_size lines have been sorted to ensure consistency
513 skipping TESTSUITE/test-mail/userx/maildirsize: dir_regex does not match
514 skipping TESTSUITE/test-mail/userx/tmp: dir_regex does not match
515 maildir_compute_size (timestamp_only): ddddddd
516 returning maildir size=sss filecount=1
517 delivering in maildir format in TESTSUITE/test-mail/userx
518 writing to tmp/MAILDIR.myhost.test.ex
519 Exim quota = 500 old size = sssss this message = sss (included)
520   file count quota = 0 count = 1
521 mailbox quota exceeded
522 maildir: time since "new" directory modified = 10s
523 Exim quota exceeded for tmp/dddddddddd.HddddddPddddd.myhost.test.ex
524 appendfile yields 1 with errno=-22 more_errno=dd
525 search_tidyup called
526 t1 transport returned DEFER for userx@test.ex
527 added retry item for T:userx@test.ex: errno=-22 more_errno=dd flags=0
528 post-process userx@test.ex (1)
529 LOG: MAIN
530   == userx@test.ex R=r1 T=t1 defer (-22): mailbox is full (MTA-imposed quota exceeded while writing to tmp/MAILDIR.myhost.test.ex)
531 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
532 changed uid/gid: post-delivery tidying
533   uid=EXIM_UID gid=EXIM_GID pid=pppp
534 set_process_info: pppp tidying up after delivering 10HmaZ-0005vi-00
535 Processing retry items
536 Succeeded addresses:
537 Failed addresses:
538 Deferred addresses:
539 userx@test.ex
540 locking TESTSUITE/spool/db/retry.lockfile
541 locked TESTSUITE/spool/db/retry.lockfile
542 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
543 returned from EXIM_DBOPEN
544 opened hints database TESTSUITE/spool/db/retry: flags=O_RDWR
545 address match test: subject=userx@test.ex pattern=*
546 test.ex in "*"? yes (matched "*")
547 userx@test.ex in "*"? yes (matched "*")
548 retry for T:userx@test.ex = * 0 0
549 dbfn_read: key=T:userx@test.ex
550 failing_interval=ttt message_age=ttt
551 Writing retry data for T:userx@test.ex
552   first failed=dddd last try=dddd next try=+86400 expired=0
553   errno=-22 more_errno=dd mailbox is full (MTA-imposed quota exceeded while writing to tmp/MAILDIR.myhost.test.ex)
554 dbfn_write: key=T:userx@test.ex
555 end of retry processing
556 delivery deferred: update_spool=1 header_rewritten=0
557 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
558 DSN: Write SPOOL :-dsn_envid NULL
559 DSN: Write SPOOL :-dsn_ret 0
560 DSN: Flags :0
561 DSN: **** SPOOL_OUT - address: |userx@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
562 Renaming spool header file: TESTSUITE/spool//input//10HmaZ-0005vi-00-H
563 Size of headers = sss
564 end delivery of 10HmaZ-0005vi-00
565 search_tidyup called
566 search_tidyup called
567 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
568 search_tidyup called
569 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
570 Exim version x.yz ....
571 changed uid/gid: forcing real = effective
572   uid=uuuu gid=CALLER_GID pid=pppp
573 configuration file is TESTSUITE/test-config
574 admin user
575 changed uid/gid: privilege not needed
576   uid=EXIM_UID gid=EXIM_GID pid=pppp
577 DSN: r1 propagating DSN
578 seeking password data for user "CALLER": cache not available
579 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
580 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
581 sender address = CALLER@test.ex
582 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
583 Sender: CALLER@test.ex
584 Recipients:
585   userx@test.ex
586 search_tidyup called
587 >>Headers received:
588
589 rewrite_one_header: type=F:
590   From: CALLER_NAME <CALLER@test.ex>
591 search_tidyup called
592 >>Headers after rewriting and local additions:
593 I Message-Id: <E10HmbA-0005vi-00@myhost.test.ex>
594 F From: CALLER_NAME <CALLER@test.ex>
595   Date: Tue, 2 Mar 1999 09:44:33 +0000
596
597 Data file name: TESTSUITE/spool//input//10HmbA-0005vi-00-D
598 Data file written for message 10HmbA-0005vi-00
599 >>Generated Received: header line
600 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
601         (envelope-from <CALLER@test.ex>)
602         id 10HmbA-0005vi-00
603         for userx@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
604 calling local_scan(); timeout=300
605 local_scan() returned 0 NULL
606 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
607 DSN: Write SPOOL :-dsn_envid NULL
608 DSN: Write SPOOL :-dsn_ret 0
609 DSN: Flags :0
610 DSN: **** SPOOL_OUT - address: |userx@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
611 Renaming spool header file: TESTSUITE/spool//input//10HmbA-0005vi-00-H
612 Size of headers = sss
613 LOG: MAIN
614   <= CALLER@test.ex U=CALLER P=local S=sss
615 search_tidyup called
616 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmbA-0005vi-00
617 Exim version x.yz ....
618 changed uid/gid: forcing real = effective
619   uid=uuuu gid=EXIM_GID pid=pppp
620 configuration file is TESTSUITE/test-config
621 trusted user
622 admin user
623 DSN: r1 propagating DSN
624 seeking password data for user "CALLER": cache not available
625 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
626 set_process_info: pppp delivering specified messages
627 set_process_info: pppp delivering 10HmbA-0005vi-00
628 Trying spool file TESTSUITE/spool//input//10HmbA-0005vi-00-D
629 reading spool file 10HmbA-0005vi-00-H
630 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
631 sender_local=1 ident=CALLER
632 Non-recipients:
633 Empty Tree
634 ---- End of tree ----
635 recipients_count=1
636 **** SPOOL_IN - No additional fields
637 body_linecount=1 message_linecount=7
638 DSN: set orcpt: NULL  flags: 0
639 Delivery address list:
640   userx@test.ex 
641 locking TESTSUITE/spool/db/retry.lockfile
642 locked TESTSUITE/spool/db/retry.lockfile
643 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
644 returned from EXIM_DBOPEN
645 opened hints database TESTSUITE/spool/db/retry: flags=O_RDONLY
646 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
647 Considering: userx@test.ex
648 unique = userx@test.ex
649 dbfn_read: key=R:test.ex
650 dbfn_read: key=R:userx@test.ex
651 dbfn_read: key=R:userx@test.ex:<CALLER@test.ex>
652 no domain retry record
653 no address retry record
654 userx@test.ex: queued for routing
655 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
656 routing userx@test.ex
657 --------> r1 router <--------
658 local_part=userx domain=test.ex
659 calling r1 router
660 r1 router called for userx@test.ex
661   domain = test.ex
662 set transport t1
663 queued for t1 transport: local_part = userx
664 domain = test.ex
665   errors_to=NULL
666   domain_data=NULL localpart_data=NULL
667 routed by r1 router
668   envelope to: userx@test.ex
669   transport: t1
670 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
671 After routing:
672   Local deliveries:
673     userx@test.ex
674   Remote deliveries:
675   Failed addresses:
676   Deferred addresses:
677 search_tidyup called
678 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
679 --------> userx@test.ex <--------
680 locking TESTSUITE/spool/db/retry.lockfile
681 locked TESTSUITE/spool/db/retry.lockfile
682 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
683 returned from EXIM_DBOPEN
684 opened hints database TESTSUITE/spool/db/retry: flags=O_RDONLY
685 dbfn_read: key=T:userx@test.ex
686 retry record exists: age=ttt (max 1w)
687   time to retry = tttt expired = 0
688 search_tidyup called
689 changed uid/gid: local delivery to userx <userx@test.ex> transport=t1
690   uid=CALLER_UID gid=CALLER_GID pid=pppp
691   home=NULL current=/
692 set_process_info: pppp delivering 10HmbA-0005vi-00 to userx using t1
693 appendfile transport entered
694 appendfile: mode=600 notify_comsat=0 quota=500 warning=0
695   directory=TESTSUITE/test-mail/userx format=maildir
696   message_prefix=null
697   message_suffix=null
698   maildir_use_size_file=yes
699 ensuring maildir directories exist in TESTSUITE/test-mail/userx
700 using regex for maildir directory selection: ^(?:cur|new|\..*)$
701 looking for maildirsize in TESTSUITE/test-mail/userx
702 reading quota parameters from maildirsize data
703 computing maildir size from maildirsize data
704 returning maildir size=sss filecount=1
705 delivering in maildir format in TESTSUITE/test-mail/userx
706 writing to tmp/MAILDIR.myhost.test.ex
707 Exim quota = 500 old size = sssss this message = sss (included)
708   file count quota = 0 count = 1
709 mailbox quota exceeded
710 maildir: time since "new" directory modified = 10s
711 Exim quota exceeded for tmp/dddddddddd.HddddddPddddd.myhost.test.ex
712 appendfile yields 1 with errno=-22 more_errno=dd
713 search_tidyup called
714 t1 transport returned DEFER for userx@test.ex
715 added retry item for T:userx@test.ex: errno=-22 more_errno=dd flags=0
716 post-process userx@test.ex (1)
717 LOG: MAIN
718   == userx@test.ex R=r1 T=t1 defer (-22): mailbox is full (MTA-imposed quota exceeded while writing to tmp/MAILDIR.myhost.test.ex)
719 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
720 changed uid/gid: post-delivery tidying
721   uid=EXIM_UID gid=EXIM_GID pid=pppp
722 set_process_info: pppp tidying up after delivering 10HmbA-0005vi-00
723 Processing retry items
724 Succeeded addresses:
725 Failed addresses:
726 Deferred addresses:
727 userx@test.ex
728 locking TESTSUITE/spool/db/retry.lockfile
729 locked TESTSUITE/spool/db/retry.lockfile
730 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
731 returned from EXIM_DBOPEN
732 opened hints database TESTSUITE/spool/db/retry: flags=O_RDWR
733 address match test: subject=userx@test.ex pattern=*
734 test.ex in "*"? yes (matched "*")
735 userx@test.ex in "*"? yes (matched "*")
736 retry for T:userx@test.ex = * 0 0
737 dbfn_read: key=T:userx@test.ex
738 failing_interval=ttt message_age=ttt
739 Writing retry data for T:userx@test.ex
740   first failed=dddd last try=dddd next try=+86400 expired=0
741   errno=-22 more_errno=dd mailbox is full (MTA-imposed quota exceeded while writing to tmp/MAILDIR.myhost.test.ex)
742 dbfn_write: key=T:userx@test.ex
743 end of retry processing
744 delivery deferred: update_spool=1 header_rewritten=0
745 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
746 DSN: Write SPOOL :-dsn_envid NULL
747 DSN: Write SPOOL :-dsn_ret 0
748 DSN: Flags :0
749 DSN: **** SPOOL_OUT - address: |userx@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
750 Renaming spool header file: TESTSUITE/spool//input//10HmbA-0005vi-00-H
751 Size of headers = sss
752 end delivery of 10HmbA-0005vi-00
753 search_tidyup called
754 search_tidyup called
755 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
756 search_tidyup called
757 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>