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