Fix 2-phase, in-order queue run delivery order
[exim.git] / test / stderr / 0374
1 Exim version x.yz ....
2 Hints DB:
3 configuration file is TESTSUITE/test-config
4 admin user
5 LOG: MAIN
6   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
7 created log directory TESTSUITE/spool/log
8 Exim version x.yz ....
9 Hints DB:
10 configuration file is TESTSUITE/test-config
11 trusted user
12 admin user
13 dropping to exim gid; retaining priv uid
14 no retry data available
15 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
16 Considering: a1@myhost.test.ex
17 unique = a1@myhost.test.ex
18 a1@myhost.test.ex: queued for routing
19 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
20 Considering: b1@myhost.test.ex
21 unique = b1@myhost.test.ex
22 b1@myhost.test.ex: queued for routing
23 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
24 Considering: c1@myhost.test.ex
25 unique = c1@myhost.test.ex
26 c1@myhost.test.ex: queued for routing
27 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
28 Considering: d1@myhost.test.ex
29 unique = d1@myhost.test.ex
30 d1@myhost.test.ex: queued for routing
31 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
32 Considering: d2@myhost.test.ex
33 unique = d2@myhost.test.ex
34 d2@myhost.test.ex: queued for routing
35 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
36 Considering: d3@myhost.test.ex
37 unique = d3@myhost.test.ex
38 d3@myhost.test.ex: queued for routing
39 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
40 routing d3@myhost.test.ex
41 --------> u1 router <--------
42 local_part=d3 domain=myhost.test.ex
43 checking local_parts
44 d3 in local_parts? no (end of list)
45 u1 router skipped: local_parts mismatch
46 --------> ut2 router <--------
47 local_part=d3 domain=myhost.test.ex
48 checking local_parts
49 d3 in local_parts? no (end of list)
50 ut2 router skipped: local_parts mismatch
51 --------> ut3 router <--------
52 local_part=d3 domain=myhost.test.ex
53 checking local_parts
54 d3 in local_parts? no (end of list)
55 ut3 router skipped: local_parts mismatch
56 --------> ut4 router <--------
57 local_part=d3 domain=myhost.test.ex
58 checking local_parts
59 calling ut4 router
60 ut4 router called for d3@myhost.test.ex
61   domain = myhost.test.ex
62 set transport ut4
63 queued for ut4 transport: local_part = d3
64 domain = myhost.test.ex
65   errors_to=NULL
66   domain_data=NULL local_part_data=^d\d
67 routed by ut4 router (unseen)
68   envelope to: d3@myhost.test.ex
69   transport: ut4
70 "unseen" set: replicated d3@myhost.test.ex
71 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
72 routing d2@myhost.test.ex
73 --------> u1 router <--------
74 local_part=d2 domain=myhost.test.ex
75 checking local_parts
76 d2 in local_parts? no (end of list)
77 u1 router skipped: local_parts mismatch
78 --------> ut2 router <--------
79 local_part=d2 domain=myhost.test.ex
80 checking local_parts
81 d2 in local_parts? no (end of list)
82 ut2 router skipped: local_parts mismatch
83 --------> ut3 router <--------
84 local_part=d2 domain=myhost.test.ex
85 checking local_parts
86 d2 in local_parts? no (end of list)
87 ut3 router skipped: local_parts mismatch
88 --------> ut4 router <--------
89 local_part=d2 domain=myhost.test.ex
90 checking local_parts
91 calling ut4 router
92 ut4 router called for d2@myhost.test.ex
93   domain = myhost.test.ex
94 queued for ut4 transport: local_part = d2
95 domain = myhost.test.ex
96   errors_to=NULL
97   domain_data=NULL local_part_data=^d\d
98 routed by ut4 router (unseen)
99   envelope to: d2@myhost.test.ex
100   transport: ut4
101 "unseen" set: replicated d2@myhost.test.ex
102 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
103 routing d1@myhost.test.ex
104 --------> u1 router <--------
105 local_part=d1 domain=myhost.test.ex
106 checking local_parts
107 d1 in local_parts? no (end of list)
108 u1 router skipped: local_parts mismatch
109 --------> ut2 router <--------
110 local_part=d1 domain=myhost.test.ex
111 checking local_parts
112 d1 in local_parts? no (end of list)
113 ut2 router skipped: local_parts mismatch
114 --------> ut3 router <--------
115 local_part=d1 domain=myhost.test.ex
116 checking local_parts
117 d1 in local_parts? no (end of list)
118 ut3 router skipped: local_parts mismatch
119 --------> ut4 router <--------
120 local_part=d1 domain=myhost.test.ex
121 checking local_parts
122 calling ut4 router
123 ut4 router called for d1@myhost.test.ex
124   domain = myhost.test.ex
125 queued for ut4 transport: local_part = d1
126 domain = myhost.test.ex
127   errors_to=NULL
128   domain_data=NULL local_part_data=^d\d
129 routed by ut4 router (unseen)
130   envelope to: d1@myhost.test.ex
131   transport: ut4
132 "unseen" set: replicated d1@myhost.test.ex
133 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
134 routing c1@myhost.test.ex
135 --------> u1 router <--------
136 local_part=c1 domain=myhost.test.ex
137 checking local_parts
138 c1 in local_parts? no (end of list)
139 u1 router skipped: local_parts mismatch
140 --------> ut2 router <--------
141 local_part=c1 domain=myhost.test.ex
142 checking local_parts
143 c1 in local_parts? no (end of list)
144 ut2 router skipped: local_parts mismatch
145 --------> ut3 router <--------
146 local_part=c1 domain=myhost.test.ex
147 checking local_parts
148 calling ut3 router
149 ut3 router called for c1@myhost.test.ex
150   domain = myhost.test.ex
151 set transport ut3
152 queued for ut3 transport: local_part = c1
153 domain = myhost.test.ex
154   errors_to=NULL
155   domain_data=NULL local_part_data=^c\d
156 routed by ut3 router (unseen)
157   envelope to: c1@myhost.test.ex
158   transport: ut3
159 "unseen" set: replicated c1@myhost.test.ex
160 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
161 routing b1@myhost.test.ex
162 --------> u1 router <--------
163 local_part=b1 domain=myhost.test.ex
164 checking local_parts
165 b1 in local_parts? no (end of list)
166 u1 router skipped: local_parts mismatch
167 --------> ut2 router <--------
168 local_part=b1 domain=myhost.test.ex
169 checking local_parts
170 calling ut2 router
171 ut2 router called for b1@myhost.test.ex
172   domain = myhost.test.ex
173 set transport ut2
174 queued for ut2 transport: local_part = b1
175 domain = myhost.test.ex
176   errors_to=NULL
177   domain_data=NULL local_part_data=^b\d
178 routed by ut2 router (unseen)
179   envelope to: b1@myhost.test.ex
180   transport: ut2
181 "unseen" set: replicated b1@myhost.test.ex
182 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
183 routing a1@myhost.test.ex
184 --------> u1 router <--------
185 local_part=a1 domain=myhost.test.ex
186 checking local_parts
187 calling u1 router
188 u1 router called for a1@myhost.test.ex
189   domain = myhost.test.ex
190 set transport ut1
191 queued for ut1 transport: local_part = a1
192 domain = myhost.test.ex
193   errors_to=NULL
194   domain_data=NULL local_part_data=^a\d
195 routed by u1 router (unseen)
196   envelope to: a1@myhost.test.ex
197   transport: ut1
198 "unseen" set: replicated a1@myhost.test.ex
199 no retry data available
200 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
201 Considering: a1@myhost.test.ex
202 unique = \0\a1@myhost.test.ex
203 a1@myhost.test.ex: queued for routing
204 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
205 Considering: b1@myhost.test.ex
206 unique = \0\b1@myhost.test.ex
207 b1@myhost.test.ex: queued for routing
208 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
209 Considering: c1@myhost.test.ex
210 unique = \0\c1@myhost.test.ex
211 c1@myhost.test.ex: queued for routing
212 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
213 Considering: d1@myhost.test.ex
214 unique = \0\d1@myhost.test.ex
215 d1@myhost.test.ex: queued for routing
216 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
217 Considering: d2@myhost.test.ex
218 unique = \0\d2@myhost.test.ex
219 d2@myhost.test.ex: queued for routing
220 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
221 Considering: d3@myhost.test.ex
222 unique = \0\d3@myhost.test.ex
223 d3@myhost.test.ex: queued for routing
224 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
225 routing d3@myhost.test.ex
226 --------> real router <--------
227 local_part=d3 domain=myhost.test.ex
228 calling real router
229 real router called for d3@myhost.test.ex
230   domain = myhost.test.ex
231 set transport real
232 queued for real transport: local_part = d3
233 domain = myhost.test.ex
234   errors_to=NULL
235   domain_data=NULL local_part_data=NULL
236 routed by real router
237   envelope to: d3@myhost.test.ex
238   transport: real
239 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
240 routing d2@myhost.test.ex
241 --------> real router <--------
242 local_part=d2 domain=myhost.test.ex
243 calling real router
244 real router called for d2@myhost.test.ex
245   domain = myhost.test.ex
246 queued for real transport: local_part = d2
247 domain = myhost.test.ex
248   errors_to=NULL
249   domain_data=NULL local_part_data=NULL
250 routed by real router
251   envelope to: d2@myhost.test.ex
252   transport: real
253 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
254 routing d1@myhost.test.ex
255 --------> real router <--------
256 local_part=d1 domain=myhost.test.ex
257 calling real router
258 real router called for d1@myhost.test.ex
259   domain = myhost.test.ex
260 queued for real transport: local_part = d1
261 domain = myhost.test.ex
262   errors_to=NULL
263   domain_data=NULL local_part_data=NULL
264 routed by real router
265   envelope to: d1@myhost.test.ex
266   transport: real
267 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
268 routing c1@myhost.test.ex
269 --------> ut4 router <--------
270 local_part=c1 domain=myhost.test.ex
271 checking local_parts
272 c1 in local_parts? no (end of list)
273 ut4 router skipped: local_parts mismatch
274 --------> real router <--------
275 local_part=c1 domain=myhost.test.ex
276 calling real router
277 real router called for c1@myhost.test.ex
278   domain = myhost.test.ex
279 queued for real transport: local_part = c1
280 domain = myhost.test.ex
281   errors_to=NULL
282   domain_data=NULL local_part_data=NULL
283 routed by real router
284   envelope to: c1@myhost.test.ex
285   transport: real
286 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
287 routing b1@myhost.test.ex
288 --------> ut3 router <--------
289 local_part=b1 domain=myhost.test.ex
290 checking local_parts
291 b1 in local_parts? no (end of list)
292 ut3 router skipped: local_parts mismatch
293 --------> ut4 router <--------
294 local_part=b1 domain=myhost.test.ex
295 checking local_parts
296 b1 in local_parts? no (end of list)
297 ut4 router skipped: local_parts mismatch
298 --------> real router <--------
299 local_part=b1 domain=myhost.test.ex
300 calling real router
301 real router called for b1@myhost.test.ex
302   domain = myhost.test.ex
303 queued for real transport: local_part = b1
304 domain = myhost.test.ex
305   errors_to=NULL
306   domain_data=NULL local_part_data=NULL
307 routed by real router
308   envelope to: b1@myhost.test.ex
309   transport: real
310 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
311 routing a1@myhost.test.ex
312 --------> ut2 router <--------
313 local_part=a1 domain=myhost.test.ex
314 checking local_parts
315 a1 in local_parts? no (end of list)
316 ut2 router skipped: local_parts mismatch
317 --------> ut3 router <--------
318 local_part=a1 domain=myhost.test.ex
319 checking local_parts
320 a1 in local_parts? no (end of list)
321 ut3 router skipped: local_parts mismatch
322 --------> ut4 router <--------
323 local_part=a1 domain=myhost.test.ex
324 checking local_parts
325 a1 in local_parts? no (end of list)
326 ut4 router skipped: local_parts mismatch
327 --------> real router <--------
328 local_part=a1 domain=myhost.test.ex
329 calling real router
330 real router called for a1@myhost.test.ex
331   domain = myhost.test.ex
332 queued for real transport: local_part = a1
333 domain = myhost.test.ex
334   errors_to=NULL
335   domain_data=NULL local_part_data=NULL
336 routed by real router
337   envelope to: a1@myhost.test.ex
338   transport: real
339 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
340 After routing:
341   Local deliveries:
342     a1@myhost.test.ex
343     b1@myhost.test.ex
344     c1@myhost.test.ex
345     d1@myhost.test.ex
346     d2@myhost.test.ex
347     d3@myhost.test.ex
348     a1@myhost.test.ex
349     b1@myhost.test.ex
350     c1@myhost.test.ex
351   Remote deliveries:
352     d1@myhost.test.ex
353     d2@myhost.test.ex
354     d3@myhost.test.ex
355   Failed addresses:
356   Deferred addresses:
357 >>>>>>>>>>>>>>>> Exim pid=p1237 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>>
358 LOG: MAIN
359   => a1 <a1@myhost.test.ex> R=real T=real
360 >>>>>>>>>>>>>>>> Exim pid=p1238 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>>
361 LOG: MAIN
362   => b1 <b1@myhost.test.ex> R=real T=real
363 >>>>>>>>>>>>>>>> Exim pid=p1239 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>>
364 LOG: MAIN
365   => c1 <c1@myhost.test.ex> R=real T=real
366 >>>>>>>>>>>>>>>> Exim pid=p1240 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>>
367 LOG: MAIN
368   => d1 <d1@myhost.test.ex> R=real T=real
369 >>>>>>>>>>>>>>>> Exim pid=p1241 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>>
370 LOG: MAIN
371   => d2 <d2@myhost.test.ex> R=real T=real
372 >>>>>>>>>>>>>>>> Exim pid=p1242 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>>
373 LOG: MAIN
374   => d3 <d3@myhost.test.ex> R=real T=real
375 >>>>>>>>>>>>>>>> Exim pid=p1243 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>>
376 LOG: MAIN
377   => a1 <a1@myhost.test.ex> R=u1 T=ut1
378 transport error EPIPE ignored
379 >>>>>>>>>>>>>>>> Exim pid=p1244 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>>
380 LOG: MAIN
381   ** b1@myhost.test.ex R=ut2 T=ut2: Child process of ut2 transport returned 127 (could mean unable to exec or command does not exist) from command: /non/existent/file
382 transport error EPIPE ignored
383 >>>>>>>>>>>>>>>> Exim pid=p1245 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>>
384 LOG: MAIN
385   == c1@myhost.test.ex R=ut3 T=ut3 defer (0): Child process of ut3 transport returned 127 (could mean unable to exec or command does not exist) from command: /non/existent/file
386 cmdlog: '220'
387 >>>>>>>>>>>>>>>> Exim pid=p1246 (transport) terminating with rc=0 >>>>>>>>>>>>>>>>
388 LOG: MAIN
389   => d1@myhost.test.ex R=ut4 T=ut4 H=127.0.0.1 [127.0.0.1] C="250 OK"
390 cmdlog: '220'
391 >>>>>>>>>>>>>>>> Exim pid=p1247 (transport) terminating with rc=0 >>>>>>>>>>>>>>>>
392 LOG: MAIN
393   == d2@myhost.test.ex R=ut4 T=ut4 defer (-44) H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:<d2@myhost.test.ex>: 450 soft error
394 cmdlog: '220'
395 >>>>>>>>>>>>>>>> Exim pid=p1248 (transport) terminating with rc=0 >>>>>>>>>>>>>>>>
396 LOG: MAIN
397   ** d3@myhost.test.ex R=ut4 T=ut4 H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:<d3@myhost.test.ex>: 550 hard error
398 Exim version x.yz ....
399 Hints DB:
400 configuration file is TESTSUITE/test-config
401 trusted user
402 admin user
403 LOG: MAIN
404   <= <> R=10HmaX-000000005vi-0000 U=EXIMUSER P=local S=sss
405 Exim version x.yz ....
406 Hints DB:
407 configuration file is TESTSUITE/test-config
408 trusted user
409 admin user
410 dropping to exim gid; retaining priv uid
411 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
412 Considering: CALLER@myhost.test.ex
413 unique = CALLER@myhost.test.ex
414 CALLER@myhost.test.ex: queued for routing
415 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
416 routing CALLER@myhost.test.ex
417 --------> u1 router <--------
418 local_part=CALLER domain=myhost.test.ex
419 checking local_parts
420 CALLER in local_parts? no (end of list)
421 u1 router skipped: local_parts mismatch
422 --------> ut2 router <--------
423 local_part=CALLER domain=myhost.test.ex
424 checking local_parts
425 CALLER in local_parts? no (end of list)
426 ut2 router skipped: local_parts mismatch
427 --------> ut3 router <--------
428 local_part=CALLER domain=myhost.test.ex
429 checking local_parts
430 CALLER in local_parts? no (end of list)
431 ut3 router skipped: local_parts mismatch
432 --------> ut4 router <--------
433 local_part=CALLER domain=myhost.test.ex
434 checking local_parts
435 CALLER in local_parts? no (end of list)
436 ut4 router skipped: local_parts mismatch
437 --------> real router <--------
438 local_part=CALLER domain=myhost.test.ex
439 calling real router
440 real router called for CALLER@myhost.test.ex
441   domain = myhost.test.ex
442 set transport real
443 queued for real transport: local_part = CALLER
444 domain = myhost.test.ex
445   errors_to=NULL
446   domain_data=NULL local_part_data=NULL
447 routed by real router
448   envelope to: CALLER@myhost.test.ex
449   transport: real
450 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
451 After routing:
452   Local deliveries:
453     CALLER@myhost.test.ex
454   Remote deliveries:
455   Failed addresses:
456   Deferred addresses:
457 >>>>>>>>>>>>>>>> Exim pid=p1251 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>>
458 LOG: MAIN
459   => CALLER <CALLER@myhost.test.ex> R=real T=real
460 LOG: MAIN
461   Completed
462 >>>>>>>>>>>>>>>> Exim pid=p1250 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
463 >>>>>>>>>>>>>>>> Exim pid=p1249 (bounce-message) terminating with rc=0 >>>>>>>>>>>>>>>>
464 >>>>>>>>>>>>>>>> Exim pid=p1236 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
465 >>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>