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