Fix 2-phase, in-order queue run delivery order
[exim.git] / test / stderr / 0403
1 Exim version x.yz ....
2 Hints DB:
3 changed uid/gid: forcing real = effective
4   uid=uuuu gid=CALLER_GID pid=p1234
5 configuration file is TESTSUITE/test-config
6 admin user
7 changed uid/gid: privilege not needed
8   uid=EXIM_UID gid=EXIM_GID pid=p1234
9 seeking password data for user "CALLER": cache not available
10 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
11 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
12 sender address = CALLER@test.ex
13 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
14 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
15 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
16 Sender: CALLER@test.ex
17 Recipients:
18   userx@test.ex
19 search_tidyup called
20 >>Headers received:
21
22 qualify & rewrite recipients list
23 rewrite rules on sender address
24 qualify and rewrite headers
25  rewrite_one_header: type=F:
26    From: CALLER_NAME <CALLER@test.ex>
27 search_tidyup called
28 >>Headers after rewriting and local additions:
29  I Message-Id: <E10HmaX-000000005vi-0000@mail.test.ex>
30  F From: CALLER_NAME <CALLER@test.ex>
31    Date: Tue, 2 Mar 1999 09:44:33 +0000
32
33 Data file name: TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
34 Data file written for message 10HmaX-000000005vi-0000
35 >>Generated Received: header line
36 P Received: from CALLER by mail.test.ex with local (Exim x.yz)
37         (envelope-from <CALLER@test.ex>)
38         id 10HmaX-000000005vi-0000
39         for userx@test.ex;
40         Tue, 2 Mar 1999 09:44:33 +0000
41 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-000000005vi-0000
42 DSN: **** SPOOL_OUT - address: <userx@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
43 Renaming spool header file: TESTSUITE/spool//input//10HmaX-000000005vi-0000-H
44 Size of headers = sss
45 LOG: MAIN
46   <= CALLER@test.ex U=CALLER P=local S=sss
47 created log directory TESTSUITE/spool/log
48 search_tidyup called
49 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -N -odi -Mc 10HmaX-000000005vi-0000
50 Exim version x.yz ....
51 Hints DB:
52 changed uid/gid: forcing real = effective
53   uid=uuuu gid=EXIM_GID pid=p1235
54 configuration file is TESTSUITE/test-config
55 trusted user
56 admin user
57 dropping to exim gid; retaining priv uid
58 seeking password data for user "CALLER": cache not available
59 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
60 set_process_info: pppp delivering specified messages
61 set_process_info: pppp delivering 10HmaX-000000005vi-0000
62 Trying spool file TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
63 reading spool file 10HmaX-000000005vi-0000-H
64 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
65 sender_local=1 ident=CALLER
66 Non-recipients:
67  Empty Tree
68 ---- End of tree ----
69 recipients_count=1
70 **** SPOOL_IN - No additional fields
71 body_linecount=0 message_linecount=8
72 DSN: set orcpt:   flags: 0x0
73 Delivery address list:
74   userx@test.ex 
75  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
76  returned from EXIM_DBOPEN: (nil)
77  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
78 no retry data available
79 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
80 Considering: userx@test.ex
81 unique = userx@test.ex
82 no   domain  retry record
83 no   address retry record
84 userx@test.ex: queued for routing
85 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
86 routing userx@test.ex
87 --------> r1 router <--------
88 local_part=userx domain=test.ex
89 checking domains
90   search_open: lsearch "TESTSUITE/aux-fixed/0403.accountfile"
91   search_find: file="TESTSUITE/aux-fixed/0403.accountfile"
92     key="userx@test.ex" partial=-1 affix=NULL starflags=0 opts="ret=key"
93   LRU list:
94     0TESTSUITE/aux-fixed/0403.accountfile
95     End
96   internal_search_find: file="TESTSUITE/aux-fixed/0403.accountfile"
97     type=lsearch key="userx@test.ex" opts=NULL
98   file lookup required for userx@test.ex
99     in TESTSUITE/aux-fixed/0403.accountfile
100   creating new cache entry
101   lookup failed
102 test.ex in domains?
103 test.ex in domains? no (end of list)
104 r1 router skipped: domains mismatch
105 --------> r2 router <--------
106 local_part=userx domain=test.ex
107 checking domains
108 test.ex in domains?
109  list element: lsearch;TESTSUITE/aux-fixed/0403.data
110  search_open: lsearch "TESTSUITE/aux-fixed/0403.data"
111  search_find: file="TESTSUITE/aux-fixed/0403.data"
112    key="test.ex" partial=-1 affix=NULL starflags=0 opts=NULL
113  LRU list:
114    0TESTSUITE/aux-fixed/0403.data
115    0TESTSUITE/aux-fixed/0403.accountfile
116    End
117  internal_search_find: file="TESTSUITE/aux-fixed/0403.data"
118    type=lsearch key="test.ex" opts=NULL
119  file lookup required for test.ex
120    in TESTSUITE/aux-fixed/0403.data
121  creating new cache entry
122  lookup yielded: [DOMAINDATA_test.ex]
123  test.ex in domains? yes (matched "lsearch;TESTSUITE/aux-fixed/0403.data")
124 checking local_parts
125 userx in local_parts?
126  list element: lsearch;TESTSUITE/aux-fixed/0403.data
127  search_open: lsearch "TESTSUITE/aux-fixed/0403.data"
128    cached open
129  search_find: file="TESTSUITE/aux-fixed/0403.data"
130    key="userx" partial=-1 affix=NULL starflags=0 opts=NULL
131  LRU list:
132    0TESTSUITE/aux-fixed/0403.data
133    0TESTSUITE/aux-fixed/0403.accountfile
134    End
135  internal_search_find: file="TESTSUITE/aux-fixed/0403.data"
136    type=lsearch key="userx" opts=NULL
137  file lookup required for userx
138    in TESTSUITE/aux-fixed/0403.data
139  creating new cache entry
140  lookup yielded: [LOCALPARTDATA_userx]
141  userx in local_parts? yes (matched "lsearch;TESTSUITE/aux-fixed/0403.data")
142 +++ROUTER:
143 +++domain_data=[DOMAINDATA_test.ex]
144 +++local_part_data=[LOCALPARTDATA_userx]
145 +++address_data=
146 +++home=/usr
147 processing address_data
148 calling r2 router
149 rda_interpret (string): 'TESTSUITE/test-mail/junk'
150 expanded: 'TESTSUITE/test-mail/junk'
151 file is not a filter file
152 parse_forward_list: TESTSUITE/test-mail/junk
153 extract item: TESTSUITE/test-mail/junk
154 set transport t1
155 r2 router generated TESTSUITE/test-mail/junk
156   pipe, file, or autoreply
157   errors_to=NULL transport=t1
158   uid=unset gid=unset home=/usr
159 routed by r2 router
160   envelope to: userx@test.ex
161   transport: <none>
162  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
163  returned from EXIM_DBOPEN: (nil)
164  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
165 no retry data available
166 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
167 Considering: TESTSUITE/test-mail/junk
168 unique = TESTSUITE/test-mail/junk:userx@test.ex
169 queued for t1 transport
170 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
171 After routing:
172   Local deliveries:
173     TESTSUITE/test-mail/junk
174   Remote deliveries:
175   Failed addresses:
176   Deferred addresses:
177 search_tidyup called
178 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
179 --------> TESTSUITE/test-mail/junk <--------
180  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
181  returned from EXIM_DBOPEN: (nil)
182  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
183 no retry data available
184 search_tidyup called
185 changed uid/gid: local delivery to TESTSUITE/test-mail/junk <TESTSUITE/test-mail/junk> transport=t1
186   uid=CALLER_UID gid=CALLER_GID pid=p1236
187   home=/usr current=/usr
188 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to TESTSUITE/test-mail/junk using t1
189 +++TRANSPORT:
190 +++domain_data=[DOMAINDATA_test.ex]
191 +++local_part_data=[LOCALPARTDATA_userx]
192 +++address_data=ADDRESSDATA
193 +++home=/usr
194 appendfile transport entered
195 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
196   file=TESTSUITE/test-mail/junk format=unix
197   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
198   message_suffix=\n
199   maildir_use_size_file=no
200   locking by lockfile fcntl 
201 *** delivery by t1 transport bypassed by -N option
202 search_tidyup called
203 >>>>>>>>>>>>>>>> Exim pid=p1236 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>>
204 journalling TESTSUITE/test-mail/junk:userx@test.ex
205 t1 transport returned OK for TESTSUITE/test-mail/junk
206 post-process TESTSUITE/test-mail/junk (0)
207 TESTSUITE/test-mail/junk delivered
208 userx@test.ex: children all complete
209 LOG: MAIN
210   *> TESTSUITE/test-mail/junk <userx@test.ex> R=r2 T=t1
211 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
212 changed uid/gid: post-delivery tidying
213   uid=EXIM_UID gid=EXIM_GID pid=p1235
214 set_process_info: pppp tidying up after delivering 10HmaX-000000005vi-0000
215 DSN: processing router : r2
216 DSN: processing successful delivery address: TESTSUITE/test-mail/junk
217 DSN: Sender_address: CALLER@test.ex
218 DSN: orcpt: NULL  flags: 0x0
219 DSN: envid: NULL  ret: 0
220 DSN: Final recipient: TESTSUITE/test-mail/junk
221 DSN: Remote SMTP server supports DSN: 0
222 DSN: not sending DSN success message
223 DSN: processing router : r2
224 DSN: processing successful delivery address: userx@test.ex
225 DSN: Sender_address: CALLER@test.ex
226 DSN: orcpt: NULL  flags: 0x0
227 DSN: envid: NULL  ret: 0
228 DSN: Final recipient: userx@test.ex
229 DSN: Remote SMTP server supports DSN: 0
230 DSN: not sending DSN success message
231 LOG: MAIN
232   Completed
233 end delivery of 10HmaX-000000005vi-0000
234 search_tidyup called
235 search_tidyup called
236 >>>>>>>>>>>>>>>> Exim pid=p1235 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
237 search_tidyup called
238 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
239 ### _data from a multi-step expansion
240 Exim version x.yz ....
241 Hints DB:
242 changed uid/gid: forcing real = effective
243   uid=uuuu gid=CALLER_GID pid=p1237
244 configuration file is TESTSUITE/test-config
245 admin user
246 changed uid/gid: privilege not needed
247   uid=EXIM_UID gid=EXIM_GID pid=p1237
248 seeking password data for user "CALLER": cache not available
249 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
250 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
251 sender address = CALLER@test.ex
252 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
253 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
254 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
255 Sender: CALLER@test.ex
256 Recipients:
257   charlie@dom1.ain
258 search_tidyup called
259 >>Headers received:
260
261 qualify & rewrite recipients list
262 rewrite rules on sender address
263 qualify and rewrite headers
264  rewrite_one_header: type=F:
265    From: CALLER_NAME <CALLER@test.ex>
266 search_tidyup called
267 >>Headers after rewriting and local additions:
268  I Message-Id: <E10HmaY-000000005vi-0000@mail.test.ex>
269  F From: CALLER_NAME <CALLER@test.ex>
270    Date: Tue, 2 Mar 1999 09:44:33 +0000
271
272 Data file name: TESTSUITE/spool//input//10HmaY-000000005vi-0000-D
273 Data file written for message 10HmaY-000000005vi-0000
274 >>Generated Received: header line
275 P Received: from CALLER by mail.test.ex with local (Exim x.yz)
276         (envelope-from <CALLER@test.ex>)
277         id 10HmaY-000000005vi-0000
278         for charlie@dom1.ain;
279         Tue, 2 Mar 1999 09:44:33 +0000
280 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaY-000000005vi-0000
281 DSN: **** SPOOL_OUT - address: <charlie@dom1.ain> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
282 Renaming spool header file: TESTSUITE/spool//input//10HmaY-000000005vi-0000-H
283 Size of headers = sss
284 LOG: MAIN
285   <= CALLER@test.ex U=CALLER P=local S=sss
286 search_tidyup called
287 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -N -odi -Mc 10HmaY-000000005vi-0000
288 Exim version x.yz ....
289 Hints DB:
290 changed uid/gid: forcing real = effective
291   uid=uuuu gid=EXIM_GID pid=p1238
292 configuration file is TESTSUITE/test-config
293 trusted user
294 admin user
295 dropping to exim gid; retaining priv uid
296 seeking password data for user "CALLER": cache not available
297 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
298 set_process_info: pppp delivering specified messages
299 set_process_info: pppp delivering 10HmaY-000000005vi-0000
300 Trying spool file TESTSUITE/spool//input//10HmaY-000000005vi-0000-D
301 reading spool file 10HmaY-000000005vi-0000-H
302 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
303 sender_local=1 ident=CALLER
304 Non-recipients:
305  Empty Tree
306 ---- End of tree ----
307 recipients_count=1
308 **** SPOOL_IN - No additional fields
309 body_linecount=0 message_linecount=8
310 DSN: set orcpt:   flags: 0x0
311 Delivery address list:
312   charlie@dom1.ain 
313  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
314  returned from EXIM_DBOPEN: (nil)
315  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
316 no retry data available
317 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
318 Considering: charlie@dom1.ain
319 unique = charlie@dom1.ain
320 no   domain  retry record
321 no   address retry record
322 charlie@dom1.ain: queued for routing
323 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
324 routing charlie@dom1.ain
325 --------> r1 router <--------
326 local_part=charlie domain=dom1.ain
327 checking domains
328   search_open: lsearch "TESTSUITE/aux-fixed/0403.accountfile"
329   search_find: file="TESTSUITE/aux-fixed/0403.accountfile"
330     key="charlie@dom1.ain" partial=-1 affix=NULL starflags=0 opts="ret=key"
331   LRU list:
332     0TESTSUITE/aux-fixed/0403.accountfile
333     End
334   internal_search_find: file="TESTSUITE/aux-fixed/0403.accountfile"
335     type=lsearch key="charlie@dom1.ain" opts=NULL
336   file lookup required for charlie@dom1.ain
337     in TESTSUITE/aux-fixed/0403.accountfile
338   creating new cache entry
339   lookup yielded: 
340   lookup yield replace by key: charlie@dom1.ain
341 dom1.ain in domains?
342  list element: dom1.ain
343  dom1.ain in domains? yes (matched "dom1.ain")
344 checking local_parts
345   search_open: lsearch "TESTSUITE/aux-fixed/0403.accountfile"
346     cached open
347   search_find: file="TESTSUITE/aux-fixed/0403.accountfile"
348     key="charlie@dom1.ain" partial=-1 affix=NULL starflags=0 opts="ret=key"
349   LRU list:
350     0TESTSUITE/aux-fixed/0403.accountfile
351     End
352   internal_search_find: file="TESTSUITE/aux-fixed/0403.accountfile"
353     type=lsearch key="charlie@dom1.ain" opts=NULL
354   cached data used for lookup of charlie@dom1.ain
355     in TESTSUITE/aux-fixed/0403.accountfile
356   lookup yielded: 
357   lookup yield replace by key: charlie@dom1.ain
358 charlie in local_parts?
359  list element: charlie
360  charlie in local_parts? yes (matched "charlie")
361 +++ROUTER:
362 +++domain_data=dom1.ain
363 +++local_part_data=charlie
364 calling r1 router
365 rda_interpret (string): 'TESTSUITE/test-mail/junk'
366 expanded: 'TESTSUITE/test-mail/junk'
367 file is not a filter file
368 parse_forward_list: TESTSUITE/test-mail/junk
369 extract item: TESTSUITE/test-mail/junk
370 set transport t1
371 r1 router generated TESTSUITE/test-mail/junk
372   pipe, file, or autoreply
373   errors_to=NULL transport=t1
374   uid=unset gid=unset home=NULL
375 routed by r1 router
376   envelope to: charlie@dom1.ain
377   transport: <none>
378  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
379  returned from EXIM_DBOPEN: (nil)
380  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
381 no retry data available
382 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
383 Considering: TESTSUITE/test-mail/junk
384 unique = TESTSUITE/test-mail/junk:charlie@dom1.ain
385 queued for t1 transport
386 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
387 After routing:
388   Local deliveries:
389     TESTSUITE/test-mail/junk
390   Remote deliveries:
391   Failed addresses:
392   Deferred addresses:
393 search_tidyup called
394 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
395 --------> TESTSUITE/test-mail/junk <--------
396  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
397  returned from EXIM_DBOPEN: (nil)
398  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
399 no retry data available
400 search_tidyup called
401 changed uid/gid: local delivery to TESTSUITE/test-mail/junk <TESTSUITE/test-mail/junk> transport=t1
402   uid=CALLER_UID gid=CALLER_GID pid=p1239
403   home=NULL current=/
404 set_process_info: pppp delivering 10HmaY-000000005vi-0000 to TESTSUITE/test-mail/junk using t1
405 +++TRANSPORT:
406 +++domain_data=dom1.ain
407 +++local_part_data=charlie
408 +++address_data=
409 +++home=
410 appendfile transport entered
411 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
412   file=TESTSUITE/test-mail/junk format=unix
413   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
414   message_suffix=\n
415   maildir_use_size_file=no
416   locking by lockfile fcntl 
417 *** delivery by t1 transport bypassed by -N option
418 search_tidyup called
419 >>>>>>>>>>>>>>>> Exim pid=p1239 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>>
420 journalling TESTSUITE/test-mail/junk:charlie@dom1.ain
421 t1 transport returned OK for TESTSUITE/test-mail/junk
422 post-process TESTSUITE/test-mail/junk (0)
423 TESTSUITE/test-mail/junk delivered
424 charlie@dom1.ain: children all complete
425 LOG: MAIN
426   *> TESTSUITE/test-mail/junk <charlie@dom1.ain> R=r1 T=t1
427 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
428 changed uid/gid: post-delivery tidying
429   uid=EXIM_UID gid=EXIM_GID pid=p1238
430 set_process_info: pppp tidying up after delivering 10HmaY-000000005vi-0000
431 DSN: processing router : r1
432 DSN: processing successful delivery address: TESTSUITE/test-mail/junk
433 DSN: Sender_address: CALLER@test.ex
434 DSN: orcpt: NULL  flags: 0x0
435 DSN: envid: NULL  ret: 0
436 DSN: Final recipient: TESTSUITE/test-mail/junk
437 DSN: Remote SMTP server supports DSN: 0
438 DSN: not sending DSN success message
439 DSN: processing router : r1
440 DSN: processing successful delivery address: charlie@dom1.ain
441 DSN: Sender_address: CALLER@test.ex
442 DSN: orcpt: NULL  flags: 0x0
443 DSN: envid: NULL  ret: 0
444 DSN: Final recipient: charlie@dom1.ain
445 DSN: Remote SMTP server supports DSN: 0
446 DSN: not sending DSN success message
447 LOG: MAIN
448   Completed
449 end delivery of 10HmaY-000000005vi-0000
450 search_tidyup called
451 search_tidyup called
452 >>>>>>>>>>>>>>>> Exim pid=p1238 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
453 search_tidyup called
454 >>>>>>>>>>>>>>>> Exim pid=p1237 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
455
456 ******** SERVER ********
457 ### _data from a multi-step expansion