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