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