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