I18N: Fix protocol recorded for a multi-SMTPUTF8-message connection. Bug 2287
[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 calling local_scan(); timeout=300
37 local_scan() returned 0 NULL
38 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
39 DSN: Write SPOOL :-dsn_envid NULL
40 DSN: Write SPOOL :-dsn_ret 0
41 DSN: Flags :0
42 DSN: **** SPOOL_OUT - address: |nofile@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
43 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-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=0xfbb95cfd -odi -Mc 10HmaX-0005vi-00
50 Exim version x.yz ....
51 changed uid/gid: forcing real = effective
52   uid=uuuu gid=EXIM_GID pid=pppp
53 configuration file is TESTSUITE/test-config
54 trusted user
55 admin user
56 seeking password data for user "CALLER": cache not available
57 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
58 set_process_info: pppp delivering specified messages
59 set_process_info: pppp delivering 10HmaX-0005vi-00
60 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
61 reading spool file 10HmaX-0005vi-00-H
62 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
63 sender_local=1 ident=CALLER
64 Non-recipients:
65 Empty Tree
66 ---- End of tree ----
67 recipients_count=1
68 **** SPOOL_IN - No additional fields
69 body_linecount=1 message_linecount=7
70 DSN: set orcpt:   flags: 0
71 Delivery address list:
72   nofile@test.ex 
73 locking TESTSUITE/spool/db/retry.lockfile
74 locked  TESTSUITE/spool/db/retry.lockfile
75 EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
76 returned from EXIM_DBOPEN: (nil)
77 ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
78 failed to open DB file TESTSUITE/spool/db/retry.lockfile: No such file or directory
79 no retry data available
80 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
81 Considering: nofile@test.ex
82 unique = nofile@test.ex
83 no domain retry record
84 no address retry record
85 nofile@test.ex: queued for routing
86 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
87 routing nofile@test.ex
88 --------> r1 router <--------
89 local_part=nofile domain=test.ex
90 calling r1 router
91 r1 router called for nofile@test.ex
92   domain = test.ex
93 set transport t1
94 queued for t1 transport: local_part = nofile
95 domain = test.ex
96   errors_to=NULL
97   domain_data=NULL localpart_data=NULL
98 routed by r1 router
99   envelope to: nofile@test.ex
100   transport: t1
101 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
102 After routing:
103   Local deliveries:
104     nofile@test.ex
105   Remote deliveries:
106   Failed addresses:
107   Deferred addresses:
108 search_tidyup called
109 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
110 --------> nofile@test.ex <--------
111 locking TESTSUITE/spool/db/retry.lockfile
112 locked  TESTSUITE/spool/db/retry.lockfile
113 EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
114 returned from EXIM_DBOPEN: (nil)
115 failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
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 cannot use sendfile for body: spoolfile not wireformat
153 writing data block fd=dddd size=sss timeout=0
154 added 'ddd 1' to maildirsize file
155 renaming temporary file
156 renamed tmp/MAILDIR.myhost.test.ex as new/MAILDIR.myhost.test.ex
157 appendfile yields 0 with errno=dd more_errno=dd
158 search_tidyup called
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=pppp
168 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
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: 0
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-0005vi-00
186 search_tidyup called
187 search_tidyup called
188 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
189 search_tidyup called
190 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
191 Exim version x.yz ....
192 changed uid/gid: forcing real = effective
193   uid=uuuu gid=CALLER_GID pid=pppp
194 configuration file is TESTSUITE/test-config
195 admin user
196 changed uid/gid: privilege not needed
197   uid=EXIM_UID gid=EXIM_GID pid=pppp
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 seeking password data for user "CALLER": cache not available
246 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
247 set_process_info: pppp delivering specified messages
248 set_process_info: pppp delivering 10HmaY-0005vi-00
249 Trying spool file TESTSUITE/spool//input//10HmaY-0005vi-00-D
250 reading spool file 10HmaY-0005vi-00-H
251 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
252 sender_local=1 ident=CALLER
253 Non-recipients:
254 Empty Tree
255 ---- End of tree ----
256 recipients_count=1
257 **** SPOOL_IN - No additional fields
258 body_linecount=1 message_linecount=7
259 DSN: set orcpt:   flags: 0
260 Delivery address list:
261   userx@test.ex 
262 locking TESTSUITE/spool/db/retry.lockfile
263 locked  TESTSUITE/spool/db/retry.lockfile
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 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: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
302 returned from EXIM_DBOPEN: (nil)
303 failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
304 no retry data available
305 search_tidyup called
306 changed uid/gid: local delivery to userx <userx@test.ex> transport=t1
307   uid=CALLER_UID gid=CALLER_GID pid=pppp
308   home=NULL current=/
309 set_process_info: pppp delivering 10HmaY-0005vi-00 to userx using t1
310 appendfile transport entered
311 appendfile: mode=600 notify_comsat=0 quota=500 warning=0
312   directory=TESTSUITE/test-mail/userx format=maildir
313   message_prefix=null
314   message_suffix=null
315   maildir_use_size_file=yes
316 ensuring maildir directories exist in TESTSUITE/test-mail/userx
317 created directory TESTSUITE/test-mail/userx/tmp
318 created directory TESTSUITE/test-mail/userx/new
319 created directory TESTSUITE/test-mail/userx/cur
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 journalling userx@test.ex
350 t1 transport returned OK for userx@test.ex
351 post-process userx@test.ex (0)
352 userx@test.ex delivered
353 LOG: MAIN
354   => userx <userx@test.ex> R=r1 T=t1
355 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
356 changed uid/gid: post-delivery tidying
357   uid=EXIM_UID gid=EXIM_GID pid=pppp
358 set_process_info: pppp tidying up after delivering 10HmaY-0005vi-00
359 Processing retry items
360 Succeeded addresses:
361  userx@test.ex: no retry items
362 Failed addresses:
363 Deferred addresses:
364 end of retry processing
365 DSN: processing router : r1
366 DSN: processing successful delivery address: userx@test.ex
367 DSN: Sender_address: CALLER@test.ex
368 DSN: orcpt: NULL  flags: 0
369 DSN: envid: NULL  ret: 0
370 DSN: Final recipient: userx@test.ex
371 DSN: Remote SMTP server supports DSN: 0
372 DSN: not sending DSN success message
373 LOG: MAIN
374   Completed
375 end delivery of 10HmaY-0005vi-00
376 search_tidyup called
377 search_tidyup called
378 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
379 search_tidyup called
380 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
381 Exim version x.yz ....
382 changed uid/gid: forcing real = effective
383   uid=uuuu gid=CALLER_GID pid=pppp
384 configuration file is TESTSUITE/test-config
385 admin user
386 changed uid/gid: privilege not needed
387   uid=EXIM_UID gid=EXIM_GID pid=pppp
388 seeking password data for user "CALLER": cache not available
389 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
390 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
391 sender address = CALLER@test.ex
392 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
393 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
394 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
395 Sender: CALLER@test.ex
396 Recipients:
397   userx@test.ex
398 search_tidyup called
399 >>Headers received:
400
401 rewrite_one_header: type=F:
402   From: CALLER_NAME <CALLER@test.ex>
403 search_tidyup called
404 >>Headers after rewriting and local additions:
405 I Message-Id: <E10HmaZ-0005vi-00@myhost.test.ex>
406 F From: CALLER_NAME <CALLER@test.ex>
407   Date: Tue, 2 Mar 1999 09:44:33 +0000
408
409 Data file name: TESTSUITE/spool//input//10HmaZ-0005vi-00-D
410 Data file written for message 10HmaZ-0005vi-00
411 >>Generated Received: header line
412 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
413         (envelope-from <CALLER@test.ex>)
414         id 10HmaZ-0005vi-00
415         for userx@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
416 calling local_scan(); timeout=300
417 local_scan() returned 0 NULL
418 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
419 DSN: Write SPOOL :-dsn_envid NULL
420 DSN: Write SPOOL :-dsn_ret 0
421 DSN: Flags :0
422 DSN: **** SPOOL_OUT - address: |userx@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
423 Renaming spool header file: TESTSUITE/spool//input//10HmaZ-0005vi-00-H
424 Size of headers = sss
425 LOG: MAIN
426   <= CALLER@test.ex U=CALLER P=local S=sss
427 search_tidyup called
428 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmaZ-0005vi-00
429 Exim version x.yz ....
430 changed uid/gid: forcing real = effective
431   uid=uuuu gid=EXIM_GID pid=pppp
432 configuration file is TESTSUITE/test-config
433 trusted user
434 admin user
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:   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: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
455 returned from EXIM_DBOPEN: (nil)
456 failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
457 no retry data available
458 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
459 Considering: userx@test.ex
460 unique = userx@test.ex
461 no domain retry record
462 no address retry record
463 userx@test.ex: queued for routing
464 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
465 routing userx@test.ex
466 --------> r1 router <--------
467 local_part=userx domain=test.ex
468 calling r1 router
469 r1 router called for userx@test.ex
470   domain = test.ex
471 set transport t1
472 queued for t1 transport: local_part = userx
473 domain = test.ex
474   errors_to=NULL
475   domain_data=NULL localpart_data=NULL
476 routed by r1 router
477   envelope to: userx@test.ex
478   transport: t1
479 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
480 After routing:
481   Local deliveries:
482     userx@test.ex
483   Remote deliveries:
484   Failed addresses:
485   Deferred addresses:
486 search_tidyup called
487 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
488 --------> userx@test.ex <--------
489 locking TESTSUITE/spool/db/retry.lockfile
490 locked  TESTSUITE/spool/db/retry.lockfile
491 EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
492 returned from EXIM_DBOPEN: (nil)
493 failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
494 no retry data available
495 search_tidyup called
496 changed uid/gid: local delivery to userx <userx@test.ex> transport=t1
497   uid=CALLER_UID gid=CALLER_GID pid=pppp
498   home=NULL current=/
499 set_process_info: pppp delivering 10HmaZ-0005vi-00 to userx using t1
500 appendfile transport entered
501 appendfile: mode=600 notify_comsat=0 quota=500 warning=0
502   directory=TESTSUITE/test-mail/userx format=maildir
503   message_prefix=null
504   message_suffix=null
505   maildir_use_size_file=yes
506 ensuring maildir directories exist in TESTSUITE/test-mail/userx
507 using regex for maildir directory selection: ^(?:cur|new|\..*)$
508 looking for maildirsize in TESTSUITE/test-mail/userx
509 reading quota parameters from maildirsize data
510 computing maildir size from maildirsize data
511 over quota and maildirsize has more than 1 entry: recalculating
512 MUNGED: the check_dir_size lines have been sorted to ensure consistency
513 check_dir_size: dir=TESTSUITE/test-mail/userx/cur sum=0 count=dd
514 check_dir_size: dir=TESTSUITE/test-mail/userx/new sum=dddd count=dd
515 skipping TESTSUITE/test-mail/userx/maildirsize: dir_regex does not match
516 skipping TESTSUITE/test-mail/userx/tmp: dir_regex does not match
517 maildir_compute_size: path=TESTSUITE/test-mail/userx
518   sum=dddd filecount=1 timestamp=ddddddd
519 checking subdirectory timestamps
520 MUNGED: the check_dir_size lines have been sorted to ensure consistency
521 skipping TESTSUITE/test-mail/userx/maildirsize: dir_regex does not match
522 skipping TESTSUITE/test-mail/userx/tmp: dir_regex does not match
523 maildir_compute_size (timestamp_only): ddddddd
524 returning maildir size=sss filecount=1
525 delivering in maildir format in TESTSUITE/test-mail/userx
526 writing to tmp/MAILDIR.myhost.test.ex
527 Exim quota = 500 old size = sssss this message = sss (included)
528   file count quota = 0 count = 1
529 mailbox quota exceeded
530 maildir: time since "new" directory modified = 10s
531 Exim quota exceeded for tmp/dddddddddd.HddddddPddddd.myhost.test.ex
532 appendfile yields 1 with errno=-22 more_errno=dd
533 search_tidyup called
534 t1 transport returned DEFER for userx@test.ex
535 added retry item for T:userx@test.ex: errno=-22 more_errno=dd flags=0
536 post-process userx@test.ex (1)
537 LOG: MAIN
538   == userx@test.ex R=r1 T=t1 defer (-22): mailbox is full (MTA-imposed quota exceeded while writing to tmp/MAILDIR.myhost.test.ex)
539 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
540 changed uid/gid: post-delivery tidying
541   uid=EXIM_UID gid=EXIM_GID pid=pppp
542 set_process_info: pppp tidying up after delivering 10HmaZ-0005vi-00
543 Processing retry items
544 Succeeded addresses:
545 Failed addresses:
546 Deferred addresses:
547  userx@test.ex
548 locking TESTSUITE/spool/db/retry.lockfile
549 locked  TESTSUITE/spool/db/retry.lockfile
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 address match test: subject=userx@test.ex pattern=*
554 test.ex in "*"? yes (matched "*")
555 userx@test.ex in "*"? yes (matched "*")
556 retry for T:userx@test.ex = * 0 0
557 dbfn_read: key=T:userx@test.ex
558 failing_interval=ttt message_age=ttt
559 Writing retry data for T:userx@test.ex
560   first failed=dddd last try=dddd next try=+86400 expired=0
561   errno=-22 more_errno=dd mailbox is full (MTA-imposed quota exceeded while writing to tmp/MAILDIR.myhost.test.ex)
562 dbfn_write: key=T:userx@test.ex
563 EXIM_DBCLOSE(0xAAAAAAAA)
564 closed hints database and lockfile
565 end of retry processing
566 delivery deferred: update_spool=1 header_rewritten=0
567 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
568 DSN: Write SPOOL :-dsn_envid NULL
569 DSN: Write SPOOL :-dsn_ret 0
570 DSN: Flags :0
571 DSN: **** SPOOL_OUT - address: |userx@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
572 Renaming spool header file: TESTSUITE/spool//input//10HmaZ-0005vi-00-H
573 Size of headers = sss
574 end delivery of 10HmaZ-0005vi-00
575 search_tidyup called
576 search_tidyup called
577 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
578 search_tidyup called
579 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
580 Exim version x.yz ....
581 changed uid/gid: forcing real = effective
582   uid=uuuu gid=CALLER_GID pid=pppp
583 configuration file is TESTSUITE/test-config
584 admin user
585 changed uid/gid: privilege not needed
586   uid=EXIM_UID gid=EXIM_GID pid=pppp
587 seeking password data for user "CALLER": cache not available
588 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
589 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
590 sender address = CALLER@test.ex
591 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
592 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
593 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
594 Sender: CALLER@test.ex
595 Recipients:
596   userx@test.ex
597 search_tidyup called
598 >>Headers received:
599
600 rewrite_one_header: type=F:
601   From: CALLER_NAME <CALLER@test.ex>
602 search_tidyup called
603 >>Headers after rewriting and local additions:
604 I Message-Id: <E10HmbA-0005vi-00@myhost.test.ex>
605 F From: CALLER_NAME <CALLER@test.ex>
606   Date: Tue, 2 Mar 1999 09:44:33 +0000
607
608 Data file name: TESTSUITE/spool//input//10HmbA-0005vi-00-D
609 Data file written for message 10HmbA-0005vi-00
610 >>Generated Received: header line
611 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
612         (envelope-from <CALLER@test.ex>)
613         id 10HmbA-0005vi-00
614         for userx@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
615 calling local_scan(); timeout=300
616 local_scan() returned 0 NULL
617 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
618 DSN: Write SPOOL :-dsn_envid NULL
619 DSN: Write SPOOL :-dsn_ret 0
620 DSN: Flags :0
621 DSN: **** SPOOL_OUT - address: |userx@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
622 Renaming spool header file: TESTSUITE/spool//input//10HmbA-0005vi-00-H
623 Size of headers = sss
624 LOG: MAIN
625   <= CALLER@test.ex U=CALLER P=local S=sss
626 search_tidyup called
627 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmbA-0005vi-00
628 Exim version x.yz ....
629 changed uid/gid: forcing real = effective
630   uid=uuuu gid=EXIM_GID pid=pppp
631 configuration file is TESTSUITE/test-config
632 trusted user
633 admin user
634 seeking password data for user "CALLER": cache not available
635 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
636 set_process_info: pppp delivering specified messages
637 set_process_info: pppp delivering 10HmbA-0005vi-00
638 Trying spool file TESTSUITE/spool//input//10HmbA-0005vi-00-D
639 reading spool file 10HmbA-0005vi-00-H
640 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
641 sender_local=1 ident=CALLER
642 Non-recipients:
643 Empty Tree
644 ---- End of tree ----
645 recipients_count=1
646 **** SPOOL_IN - No additional fields
647 body_linecount=1 message_linecount=7
648 DSN: set orcpt:   flags: 0
649 Delivery address list:
650   userx@test.ex 
651 locking TESTSUITE/spool/db/retry.lockfile
652 locked  TESTSUITE/spool/db/retry.lockfile
653 EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
654 returned from EXIM_DBOPEN: 0xAAAAAAAA
655 opened hints database TESTSUITE/spool/db/retry: flags=O_RDONLY
656 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
657 Considering: userx@test.ex
658 unique = userx@test.ex
659 dbfn_read: key=R:test.ex
660 dbfn_read: key=R:userx@test.ex
661 dbfn_read: key=R:userx@test.ex:<CALLER@test.ex>
662 no domain retry record
663 no address retry record
664 userx@test.ex: queued for routing
665 EXIM_DBCLOSE(0xAAAAAAAA)
666 closed hints database and lockfile
667 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
668 routing userx@test.ex
669 --------> r1 router <--------
670 local_part=userx domain=test.ex
671 calling r1 router
672 r1 router called for userx@test.ex
673   domain = test.ex
674 set transport t1
675 queued for t1 transport: local_part = userx
676 domain = test.ex
677   errors_to=NULL
678   domain_data=NULL localpart_data=NULL
679 routed by r1 router
680   envelope to: userx@test.ex
681   transport: t1
682 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
683 After routing:
684   Local deliveries:
685     userx@test.ex
686   Remote deliveries:
687   Failed addresses:
688   Deferred addresses:
689 search_tidyup called
690 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
691 --------> userx@test.ex <--------
692 locking TESTSUITE/spool/db/retry.lockfile
693 locked  TESTSUITE/spool/db/retry.lockfile
694 EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
695 returned from EXIM_DBOPEN: 0xAAAAAAAA
696 opened hints database TESTSUITE/spool/db/retry: flags=O_RDONLY
697 dbfn_read: key=T:userx@test.ex
698 retry record exists: age=ttt (max 1w)
699   time to retry = tttt expired = 0
700 EXIM_DBCLOSE(0xAAAAAAAA)
701 closed hints database and lockfile
702 search_tidyup called
703 changed uid/gid: local delivery to userx <userx@test.ex> transport=t1
704   uid=CALLER_UID gid=CALLER_GID pid=pppp
705   home=NULL current=/
706 set_process_info: pppp delivering 10HmbA-0005vi-00 to userx using t1
707 appendfile transport entered
708 appendfile: mode=600 notify_comsat=0 quota=500 warning=0
709   directory=TESTSUITE/test-mail/userx format=maildir
710   message_prefix=null
711   message_suffix=null
712   maildir_use_size_file=yes
713 ensuring maildir directories exist in TESTSUITE/test-mail/userx
714 using regex for maildir directory selection: ^(?:cur|new|\..*)$
715 looking for maildirsize in TESTSUITE/test-mail/userx
716 reading quota parameters from maildirsize data
717 computing maildir size from maildirsize data
718 returning maildir size=sss filecount=1
719 delivering in maildir format in TESTSUITE/test-mail/userx
720 writing to tmp/MAILDIR.myhost.test.ex
721 Exim quota = 500 old size = sssss this message = sss (included)
722   file count quota = 0 count = 1
723 mailbox quota exceeded
724 maildir: time since "new" directory modified = 10s
725 Exim quota exceeded for tmp/dddddddddd.HddddddPddddd.myhost.test.ex
726 appendfile yields 1 with errno=-22 more_errno=dd
727 search_tidyup called
728 t1 transport returned DEFER for userx@test.ex
729 added retry item for T:userx@test.ex: errno=-22 more_errno=dd flags=0
730 post-process userx@test.ex (1)
731 LOG: MAIN
732   == userx@test.ex R=r1 T=t1 defer (-22): mailbox is full (MTA-imposed quota exceeded while writing to tmp/MAILDIR.myhost.test.ex)
733 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
734 changed uid/gid: post-delivery tidying
735   uid=EXIM_UID gid=EXIM_GID pid=pppp
736 set_process_info: pppp tidying up after delivering 10HmbA-0005vi-00
737 Processing retry items
738 Succeeded addresses:
739 Failed addresses:
740 Deferred addresses:
741  userx@test.ex
742 locking TESTSUITE/spool/db/retry.lockfile
743 locked  TESTSUITE/spool/db/retry.lockfile
744 EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDWR
745 returned from EXIM_DBOPEN: 0xAAAAAAAA
746 opened hints database TESTSUITE/spool/db/retry: flags=O_RDWR
747 address match test: subject=userx@test.ex pattern=*
748 test.ex in "*"? yes (matched "*")
749 userx@test.ex in "*"? yes (matched "*")
750 retry for T:userx@test.ex = * 0 0
751 dbfn_read: key=T:userx@test.ex
752 failing_interval=ttt message_age=ttt
753 Writing retry data for T:userx@test.ex
754   first failed=dddd last try=dddd next try=+86400 expired=0
755   errno=-22 more_errno=dd mailbox is full (MTA-imposed quota exceeded while writing to tmp/MAILDIR.myhost.test.ex)
756 dbfn_write: key=T:userx@test.ex
757 EXIM_DBCLOSE(0xAAAAAAAA)
758 closed hints database and lockfile
759 end of retry processing
760 delivery deferred: update_spool=1 header_rewritten=0
761 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
762 DSN: Write SPOOL :-dsn_envid NULL
763 DSN: Write SPOOL :-dsn_ret 0
764 DSN: Flags :0
765 DSN: **** SPOOL_OUT - address: |userx@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
766 Renaming spool header file: TESTSUITE/spool//input//10HmbA-0005vi-00-H
767 Size of headers = sss
768 end delivery of 10HmbA-0005vi-00
769 search_tidyup called
770 search_tidyup called
771 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
772 search_tidyup called
773 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>