Fix spurious detection of timeout while writing to transport filter
[users/jgh/exim.git] / test / stderr / 0375
1 Exim version x.yz ....
2 configuration file is TESTSUITE/test-config
3 admin user
4 LOG: MAIN
5   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
6 created log directory TESTSUITE/spool/log
7 Exim version x.yz ....
8 configuration file is TESTSUITE/test-config
9 trusted user
10 admin user
11 dropping to exim gid; retaining priv uid
12 locking TESTSUITE/spool/db/retry.lockfile
13 no retry data available
14 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
15 Considering: a1@myhost.test.ex
16 unique = a1@myhost.test.ex
17 a1@myhost.test.ex: queued for routing
18 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
19 Considering: b1@myhost.test.ex
20 unique = b1@myhost.test.ex
21 b1@myhost.test.ex: queued for routing
22 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
23 Considering: c1@myhost.test.ex
24 unique = c1@myhost.test.ex
25 c1@myhost.test.ex: queued for routing
26 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
27 Considering: d1@myhost.test.ex
28 unique = d1@myhost.test.ex
29 d1@myhost.test.ex: queued for routing
30 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
31 Considering: d2@myhost.test.ex
32 unique = d2@myhost.test.ex
33 d2@myhost.test.ex: queued for routing
34 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
35 Considering: d3@myhost.test.ex
36 unique = d3@myhost.test.ex
37 d3@myhost.test.ex: queued for routing
38 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
39 Considering: e1@myhost.test.ex
40 unique = e1@myhost.test.ex
41 e1@myhost.test.ex: queued for routing
42 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
43 Considering: f1@myhost.test.ex
44 unique = f1@myhost.test.ex
45 f1@myhost.test.ex: queued for routing
46 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
47 Considering: f2@myhost.test.ex
48 unique = f2@myhost.test.ex
49 f2@myhost.test.ex: queued for routing
50 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
51 Considering: f3@myhost.test.ex
52 unique = f3@myhost.test.ex
53 f3@myhost.test.ex: queued for routing
54 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
55 Considering: g1@myhost.test.ex
56 unique = g1@myhost.test.ex
57 g1@myhost.test.ex: queued for routing
58 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
59 routing g1@myhost.test.ex
60 --------> u1 router <--------
61 local_part=g1 domain=myhost.test.ex
62 checking local_parts
63 u1 router skipped: local_parts mismatch
64 --------> ut2 router <--------
65 local_part=g1 domain=myhost.test.ex
66 checking local_parts
67 ut2 router skipped: local_parts mismatch
68 --------> ut3 router <--------
69 local_part=g1 domain=myhost.test.ex
70 checking local_parts
71 ut3 router skipped: local_parts mismatch
72 --------> ut4 router <--------
73 local_part=g1 domain=myhost.test.ex
74 checking local_parts
75 ut4 router skipped: local_parts mismatch
76 --------> ut5 router <--------
77 local_part=g1 domain=myhost.test.ex
78 checking local_parts
79 ut5 router skipped: local_parts mismatch
80 --------> ut6 router <--------
81 local_part=g1 domain=myhost.test.ex
82 checking local_parts
83 ut6 router skipped: local_parts mismatch
84 --------> ut7 router <--------
85 local_part=g1 domain=myhost.test.ex
86 checking local_parts
87 processing address_data
88 LOG: MAIN
89   == g1@myhost.test.ex R=ut7 defer (-1): expansion of "$bad expansion" failed in ut7 router: unknown variable name "bad"
90 log writing disabled
91 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
92 routing f3@myhost.test.ex
93 --------> u1 router <--------
94 local_part=f3 domain=myhost.test.ex
95 checking local_parts
96 u1 router skipped: local_parts mismatch
97 --------> ut2 router <--------
98 local_part=f3 domain=myhost.test.ex
99 checking local_parts
100 ut2 router skipped: local_parts mismatch
101 --------> ut3 router <--------
102 local_part=f3 domain=myhost.test.ex
103 checking local_parts
104 ut3 router skipped: local_parts mismatch
105 --------> ut4 router <--------
106 local_part=f3 domain=myhost.test.ex
107 checking local_parts
108 ut4 router skipped: local_parts mismatch
109 --------> ut5 router <--------
110 local_part=f3 domain=myhost.test.ex
111 checking local_parts
112 ut5 router skipped: local_parts mismatch
113 --------> ut6 router <--------
114 local_part=f3 domain=myhost.test.ex
115 checking local_parts
116 processing address_data
117 calling ut6 router
118 ut6 router called for f3@myhost.test.ex
119   domain = myhost.test.ex
120 set transport ut6
121 queued for ut6 transport: local_part = f3
122 domain = myhost.test.ex
123   errors_to=
124   domain_data=NULL localpart_data=NULL
125 routed by ut6 router (unseen)
126   envelope to: f3@myhost.test.ex
127   transport: ut6
128   errors to 
129 "unseen" set: replicated f3@myhost.test.ex
130 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
131 routing f2@myhost.test.ex
132 --------> u1 router <--------
133 local_part=f2 domain=myhost.test.ex
134 checking local_parts
135 u1 router skipped: local_parts mismatch
136 --------> ut2 router <--------
137 local_part=f2 domain=myhost.test.ex
138 checking local_parts
139 ut2 router skipped: local_parts mismatch
140 --------> ut3 router <--------
141 local_part=f2 domain=myhost.test.ex
142 checking local_parts
143 ut3 router skipped: local_parts mismatch
144 --------> ut4 router <--------
145 local_part=f2 domain=myhost.test.ex
146 checking local_parts
147 ut4 router skipped: local_parts mismatch
148 --------> ut5 router <--------
149 local_part=f2 domain=myhost.test.ex
150 checking local_parts
151 ut5 router skipped: local_parts mismatch
152 --------> ut6 router <--------
153 local_part=f2 domain=myhost.test.ex
154 checking local_parts
155 processing address_data
156 calling ut6 router
157 ut6 router called for f2@myhost.test.ex
158   domain = myhost.test.ex
159 queued for ut6 transport: local_part = f2
160 domain = myhost.test.ex
161   errors_to=
162   domain_data=NULL localpart_data=NULL
163 routed by ut6 router (unseen)
164   envelope to: f2@myhost.test.ex
165   transport: ut6
166   errors to 
167 "unseen" set: replicated f2@myhost.test.ex
168 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
169 routing f1@myhost.test.ex
170 --------> u1 router <--------
171 local_part=f1 domain=myhost.test.ex
172 checking local_parts
173 u1 router skipped: local_parts mismatch
174 --------> ut2 router <--------
175 local_part=f1 domain=myhost.test.ex
176 checking local_parts
177 ut2 router skipped: local_parts mismatch
178 --------> ut3 router <--------
179 local_part=f1 domain=myhost.test.ex
180 checking local_parts
181 ut3 router skipped: local_parts mismatch
182 --------> ut4 router <--------
183 local_part=f1 domain=myhost.test.ex
184 checking local_parts
185 ut4 router skipped: local_parts mismatch
186 --------> ut5 router <--------
187 local_part=f1 domain=myhost.test.ex
188 checking local_parts
189 ut5 router skipped: local_parts mismatch
190 --------> ut6 router <--------
191 local_part=f1 domain=myhost.test.ex
192 checking local_parts
193 processing address_data
194 calling ut6 router
195 ut6 router called for f1@myhost.test.ex
196   domain = myhost.test.ex
197 queued for ut6 transport: local_part = f1
198 domain = myhost.test.ex
199   errors_to=
200   domain_data=NULL localpart_data=NULL
201 routed by ut6 router (unseen)
202   envelope to: f1@myhost.test.ex
203   transport: ut6
204   errors to 
205 "unseen" set: replicated f1@myhost.test.ex
206 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
207 routing e1@myhost.test.ex
208 --------> u1 router <--------
209 local_part=e1 domain=myhost.test.ex
210 checking local_parts
211 u1 router skipped: local_parts mismatch
212 --------> ut2 router <--------
213 local_part=e1 domain=myhost.test.ex
214 checking local_parts
215 ut2 router skipped: local_parts mismatch
216 --------> ut3 router <--------
217 local_part=e1 domain=myhost.test.ex
218 checking local_parts
219 ut3 router skipped: local_parts mismatch
220 --------> ut4 router <--------
221 local_part=e1 domain=myhost.test.ex
222 checking local_parts
223 ut4 router skipped: local_parts mismatch
224 --------> ut5 router <--------
225 local_part=e1 domain=myhost.test.ex
226 checking local_parts
227 calling ut5 router
228 ut5 router called for e1@myhost.test.ex
229   domain = myhost.test.ex
230 set transport ut5
231 queued for ut5 transport: local_part = e1
232 domain = myhost.test.ex
233   errors_to=NULL
234   domain_data=NULL localpart_data=NULL
235 routed by ut5 router (unseen)
236   envelope to: e1@myhost.test.ex
237   transport: ut5
238 "unseen" set: replicated e1@myhost.test.ex
239 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
240 routing d3@myhost.test.ex
241 --------> u1 router <--------
242 local_part=d3 domain=myhost.test.ex
243 checking local_parts
244 u1 router skipped: local_parts mismatch
245 --------> ut2 router <--------
246 local_part=d3 domain=myhost.test.ex
247 checking local_parts
248 ut2 router skipped: local_parts mismatch
249 --------> ut3 router <--------
250 local_part=d3 domain=myhost.test.ex
251 checking local_parts
252 ut3 router skipped: local_parts mismatch
253 --------> ut4 router <--------
254 local_part=d3 domain=myhost.test.ex
255 checking local_parts
256 calling ut4 router
257 ut4 router called for d3@myhost.test.ex
258   domain = myhost.test.ex
259 set transport ut4
260 queued for ut4 transport: local_part = d3
261 domain = myhost.test.ex
262   errors_to=
263   domain_data=NULL localpart_data=NULL
264 routed by ut4 router (unseen)
265   envelope to: d3@myhost.test.ex
266   transport: ut4
267   errors to 
268 "unseen" set: replicated d3@myhost.test.ex
269 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
270 routing d2@myhost.test.ex
271 --------> u1 router <--------
272 local_part=d2 domain=myhost.test.ex
273 checking local_parts
274 u1 router skipped: local_parts mismatch
275 --------> ut2 router <--------
276 local_part=d2 domain=myhost.test.ex
277 checking local_parts
278 ut2 router skipped: local_parts mismatch
279 --------> ut3 router <--------
280 local_part=d2 domain=myhost.test.ex
281 checking local_parts
282 ut3 router skipped: local_parts mismatch
283 --------> ut4 router <--------
284 local_part=d2 domain=myhost.test.ex
285 checking local_parts
286 calling ut4 router
287 ut4 router called for d2@myhost.test.ex
288   domain = myhost.test.ex
289 queued for ut4 transport: local_part = d2
290 domain = myhost.test.ex
291   errors_to=
292   domain_data=NULL localpart_data=NULL
293 routed by ut4 router (unseen)
294   envelope to: d2@myhost.test.ex
295   transport: ut4
296   errors to 
297 "unseen" set: replicated d2@myhost.test.ex
298 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
299 routing d1@myhost.test.ex
300 --------> u1 router <--------
301 local_part=d1 domain=myhost.test.ex
302 checking local_parts
303 u1 router skipped: local_parts mismatch
304 --------> ut2 router <--------
305 local_part=d1 domain=myhost.test.ex
306 checking local_parts
307 ut2 router skipped: local_parts mismatch
308 --------> ut3 router <--------
309 local_part=d1 domain=myhost.test.ex
310 checking local_parts
311 ut3 router skipped: local_parts mismatch
312 --------> ut4 router <--------
313 local_part=d1 domain=myhost.test.ex
314 checking local_parts
315 calling ut4 router
316 ut4 router called for d1@myhost.test.ex
317   domain = myhost.test.ex
318 queued for ut4 transport: local_part = d1
319 domain = myhost.test.ex
320   errors_to=
321   domain_data=NULL localpart_data=NULL
322 routed by ut4 router (unseen)
323   envelope to: d1@myhost.test.ex
324   transport: ut4
325   errors to 
326 "unseen" set: replicated d1@myhost.test.ex
327 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
328 routing c1@myhost.test.ex
329 --------> u1 router <--------
330 local_part=c1 domain=myhost.test.ex
331 checking local_parts
332 u1 router skipped: local_parts mismatch
333 --------> ut2 router <--------
334 local_part=c1 domain=myhost.test.ex
335 checking local_parts
336 ut2 router skipped: local_parts mismatch
337 --------> ut3 router <--------
338 local_part=c1 domain=myhost.test.ex
339 checking local_parts
340 calling ut3 router
341 ut3 router called for c1@myhost.test.ex
342   domain = myhost.test.ex
343 set transport ut3
344 queued for ut3 transport: local_part = c1
345 domain = myhost.test.ex
346   errors_to=
347   domain_data=NULL localpart_data=NULL
348 routed by ut3 router (unseen)
349   envelope to: c1@myhost.test.ex
350   transport: ut3
351   errors to 
352 "unseen" set: replicated c1@myhost.test.ex
353 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
354 routing b1@myhost.test.ex
355 --------> u1 router <--------
356 local_part=b1 domain=myhost.test.ex
357 checking local_parts
358 u1 router skipped: local_parts mismatch
359 --------> ut2 router <--------
360 local_part=b1 domain=myhost.test.ex
361 checking local_parts
362 calling ut2 router
363 ut2 router called for b1@myhost.test.ex
364   domain = myhost.test.ex
365 set transport ut2
366 queued for ut2 transport: local_part = b1
367 domain = myhost.test.ex
368   errors_to=
369   domain_data=NULL localpart_data=NULL
370 routed by ut2 router (unseen)
371   envelope to: b1@myhost.test.ex
372   transport: ut2
373   errors to 
374 "unseen" set: replicated b1@myhost.test.ex
375 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
376 routing a1@myhost.test.ex
377 --------> u1 router <--------
378 local_part=a1 domain=myhost.test.ex
379 checking local_parts
380 calling u1 router
381 u1 router called for a1@myhost.test.ex
382   domain = myhost.test.ex
383 set transport ut1
384 queued for ut1 transport: local_part = a1
385 domain = myhost.test.ex
386   errors_to=
387   domain_data=NULL localpart_data=NULL
388 routed by u1 router (unseen)
389   envelope to: a1@myhost.test.ex
390   transport: ut1
391   errors to 
392 "unseen" set: replicated a1@myhost.test.ex
393 locking TESTSUITE/spool/db/retry.lockfile
394 no retry data available
395 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
396 Considering: a1@myhost.test.ex
397 unique = \0\a1@myhost.test.ex
398 a1@myhost.test.ex: queued for routing
399 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
400 Considering: b1@myhost.test.ex
401 unique = \0\b1@myhost.test.ex
402 b1@myhost.test.ex: queued for routing
403 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
404 Considering: c1@myhost.test.ex
405 unique = \0\c1@myhost.test.ex
406 c1@myhost.test.ex: queued for routing
407 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
408 Considering: d1@myhost.test.ex
409 unique = \0\d1@myhost.test.ex
410 d1@myhost.test.ex: queued for routing
411 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
412 Considering: d2@myhost.test.ex
413 unique = \0\d2@myhost.test.ex
414 d2@myhost.test.ex: queued for routing
415 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
416 Considering: d3@myhost.test.ex
417 unique = \0\d3@myhost.test.ex
418 d3@myhost.test.ex: queued for routing
419 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
420 Considering: e1@myhost.test.ex
421 unique = \0\e1@myhost.test.ex
422 e1@myhost.test.ex: queued for routing
423 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
424 Considering: f1@myhost.test.ex
425 unique = \0\f1@myhost.test.ex
426 f1@myhost.test.ex: queued for routing
427 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
428 Considering: f2@myhost.test.ex
429 unique = \0\f2@myhost.test.ex
430 f2@myhost.test.ex: queued for routing
431 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
432 Considering: f3@myhost.test.ex
433 unique = \0\f3@myhost.test.ex
434 f3@myhost.test.ex: queued for routing
435 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
436 routing f3@myhost.test.ex
437 --------> ut7 router <--------
438 local_part=f3 domain=myhost.test.ex
439 checking local_parts
440 ut7 router skipped: local_parts mismatch
441 --------> ut8 router <--------
442 local_part=f3 domain=myhost.test.ex
443 checking local_parts
444 ut8 router skipped: local_parts mismatch
445 --------> real router <--------
446 local_part=f3 domain=myhost.test.ex
447 calling real router
448 real router called for f3@myhost.test.ex
449   domain = myhost.test.ex
450 set transport real
451 queued for real transport: local_part = f3
452 domain = myhost.test.ex
453   errors_to=NULL
454   domain_data=NULL localpart_data=NULL
455 routed by real router
456   envelope to: f3@myhost.test.ex
457   transport: real
458 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
459 routing f2@myhost.test.ex
460 --------> ut7 router <--------
461 local_part=f2 domain=myhost.test.ex
462 checking local_parts
463 ut7 router skipped: local_parts mismatch
464 --------> ut8 router <--------
465 local_part=f2 domain=myhost.test.ex
466 checking local_parts
467 ut8 router skipped: local_parts mismatch
468 --------> real router <--------
469 local_part=f2 domain=myhost.test.ex
470 calling real router
471 real router called for f2@myhost.test.ex
472   domain = myhost.test.ex
473 queued for real transport: local_part = f2
474 domain = myhost.test.ex
475   errors_to=NULL
476   domain_data=NULL localpart_data=NULL
477 routed by real router
478   envelope to: f2@myhost.test.ex
479   transport: real
480 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
481 routing f1@myhost.test.ex
482 --------> ut7 router <--------
483 local_part=f1 domain=myhost.test.ex
484 checking local_parts
485 ut7 router skipped: local_parts mismatch
486 --------> ut8 router <--------
487 local_part=f1 domain=myhost.test.ex
488 checking local_parts
489 ut8 router skipped: local_parts mismatch
490 --------> real router <--------
491 local_part=f1 domain=myhost.test.ex
492 calling real router
493 real router called for f1@myhost.test.ex
494   domain = myhost.test.ex
495 queued for real transport: local_part = f1
496 domain = myhost.test.ex
497   errors_to=NULL
498   domain_data=NULL localpart_data=NULL
499 routed by real router
500   envelope to: f1@myhost.test.ex
501   transport: real
502 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
503 routing e1@myhost.test.ex
504 --------> ut6 router <--------
505 local_part=e1 domain=myhost.test.ex
506 checking local_parts
507 ut6 router skipped: local_parts mismatch
508 --------> ut7 router <--------
509 local_part=e1 domain=myhost.test.ex
510 checking local_parts
511 ut7 router skipped: local_parts mismatch
512 --------> ut8 router <--------
513 local_part=e1 domain=myhost.test.ex
514 checking local_parts
515 ut8 router skipped: local_parts mismatch
516 --------> real router <--------
517 local_part=e1 domain=myhost.test.ex
518 calling real router
519 real router called for e1@myhost.test.ex
520   domain = myhost.test.ex
521 queued for real transport: local_part = e1
522 domain = myhost.test.ex
523   errors_to=NULL
524   domain_data=NULL localpart_data=NULL
525 routed by real router
526   envelope to: e1@myhost.test.ex
527   transport: real
528 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
529 routing d3@myhost.test.ex
530 --------> ut5 router <--------
531 local_part=d3 domain=myhost.test.ex
532 checking local_parts
533 ut5 router skipped: local_parts mismatch
534 --------> ut6 router <--------
535 local_part=d3 domain=myhost.test.ex
536 checking local_parts
537 ut6 router skipped: local_parts mismatch
538 --------> ut7 router <--------
539 local_part=d3 domain=myhost.test.ex
540 checking local_parts
541 ut7 router skipped: local_parts mismatch
542 --------> ut8 router <--------
543 local_part=d3 domain=myhost.test.ex
544 checking local_parts
545 ut8 router skipped: local_parts mismatch
546 --------> real router <--------
547 local_part=d3 domain=myhost.test.ex
548 calling real router
549 real router called for d3@myhost.test.ex
550   domain = myhost.test.ex
551 queued for real transport: local_part = d3
552 domain = myhost.test.ex
553   errors_to=NULL
554   domain_data=NULL localpart_data=NULL
555 routed by real router
556   envelope to: d3@myhost.test.ex
557   transport: real
558 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
559 routing d2@myhost.test.ex
560 --------> ut5 router <--------
561 local_part=d2 domain=myhost.test.ex
562 checking local_parts
563 ut5 router skipped: local_parts mismatch
564 --------> ut6 router <--------
565 local_part=d2 domain=myhost.test.ex
566 checking local_parts
567 ut6 router skipped: local_parts mismatch
568 --------> ut7 router <--------
569 local_part=d2 domain=myhost.test.ex
570 checking local_parts
571 ut7 router skipped: local_parts mismatch
572 --------> ut8 router <--------
573 local_part=d2 domain=myhost.test.ex
574 checking local_parts
575 ut8 router skipped: local_parts mismatch
576 --------> real router <--------
577 local_part=d2 domain=myhost.test.ex
578 calling real router
579 real router called for d2@myhost.test.ex
580   domain = myhost.test.ex
581 queued for real transport: local_part = d2
582 domain = myhost.test.ex
583   errors_to=NULL
584   domain_data=NULL localpart_data=NULL
585 routed by real router
586   envelope to: d2@myhost.test.ex
587   transport: real
588 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
589 routing d1@myhost.test.ex
590 --------> ut5 router <--------
591 local_part=d1 domain=myhost.test.ex
592 checking local_parts
593 ut5 router skipped: local_parts mismatch
594 --------> ut6 router <--------
595 local_part=d1 domain=myhost.test.ex
596 checking local_parts
597 ut6 router skipped: local_parts mismatch
598 --------> ut7 router <--------
599 local_part=d1 domain=myhost.test.ex
600 checking local_parts
601 ut7 router skipped: local_parts mismatch
602 --------> ut8 router <--------
603 local_part=d1 domain=myhost.test.ex
604 checking local_parts
605 ut8 router skipped: local_parts mismatch
606 --------> real router <--------
607 local_part=d1 domain=myhost.test.ex
608 calling real router
609 real router called for d1@myhost.test.ex
610   domain = myhost.test.ex
611 queued for real transport: local_part = d1
612 domain = myhost.test.ex
613   errors_to=NULL
614   domain_data=NULL localpart_data=NULL
615 routed by real router
616   envelope to: d1@myhost.test.ex
617   transport: real
618 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
619 routing c1@myhost.test.ex
620 --------> ut4 router <--------
621 local_part=c1 domain=myhost.test.ex
622 checking local_parts
623 ut4 router skipped: local_parts mismatch
624 --------> ut5 router <--------
625 local_part=c1 domain=myhost.test.ex
626 checking local_parts
627 ut5 router skipped: local_parts mismatch
628 --------> ut6 router <--------
629 local_part=c1 domain=myhost.test.ex
630 checking local_parts
631 ut6 router skipped: local_parts mismatch
632 --------> ut7 router <--------
633 local_part=c1 domain=myhost.test.ex
634 checking local_parts
635 ut7 router skipped: local_parts mismatch
636 --------> ut8 router <--------
637 local_part=c1 domain=myhost.test.ex
638 checking local_parts
639 ut8 router skipped: local_parts mismatch
640 --------> real router <--------
641 local_part=c1 domain=myhost.test.ex
642 calling real router
643 real router called for c1@myhost.test.ex
644   domain = myhost.test.ex
645 queued for real transport: local_part = c1
646 domain = myhost.test.ex
647   errors_to=NULL
648   domain_data=NULL localpart_data=NULL
649 routed by real router
650   envelope to: c1@myhost.test.ex
651   transport: real
652 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
653 routing b1@myhost.test.ex
654 --------> ut3 router <--------
655 local_part=b1 domain=myhost.test.ex
656 checking local_parts
657 ut3 router skipped: local_parts mismatch
658 --------> ut4 router <--------
659 local_part=b1 domain=myhost.test.ex
660 checking local_parts
661 ut4 router skipped: local_parts mismatch
662 --------> ut5 router <--------
663 local_part=b1 domain=myhost.test.ex
664 checking local_parts
665 ut5 router skipped: local_parts mismatch
666 --------> ut6 router <--------
667 local_part=b1 domain=myhost.test.ex
668 checking local_parts
669 ut6 router skipped: local_parts mismatch
670 --------> ut7 router <--------
671 local_part=b1 domain=myhost.test.ex
672 checking local_parts
673 ut7 router skipped: local_parts mismatch
674 --------> ut8 router <--------
675 local_part=b1 domain=myhost.test.ex
676 checking local_parts
677 ut8 router skipped: local_parts mismatch
678 --------> real router <--------
679 local_part=b1 domain=myhost.test.ex
680 calling real router
681 real router called for b1@myhost.test.ex
682   domain = myhost.test.ex
683 queued for real transport: local_part = b1
684 domain = myhost.test.ex
685   errors_to=NULL
686   domain_data=NULL localpart_data=NULL
687 routed by real router
688   envelope to: b1@myhost.test.ex
689   transport: real
690 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
691 routing a1@myhost.test.ex
692 --------> ut2 router <--------
693 local_part=a1 domain=myhost.test.ex
694 checking local_parts
695 ut2 router skipped: local_parts mismatch
696 --------> ut3 router <--------
697 local_part=a1 domain=myhost.test.ex
698 checking local_parts
699 ut3 router skipped: local_parts mismatch
700 --------> ut4 router <--------
701 local_part=a1 domain=myhost.test.ex
702 checking local_parts
703 ut4 router skipped: local_parts mismatch
704 --------> ut5 router <--------
705 local_part=a1 domain=myhost.test.ex
706 checking local_parts
707 ut5 router skipped: local_parts mismatch
708 --------> ut6 router <--------
709 local_part=a1 domain=myhost.test.ex
710 checking local_parts
711 ut6 router skipped: local_parts mismatch
712 --------> ut7 router <--------
713 local_part=a1 domain=myhost.test.ex
714 checking local_parts
715 ut7 router skipped: local_parts mismatch
716 --------> ut8 router <--------
717 local_part=a1 domain=myhost.test.ex
718 checking local_parts
719 ut8 router skipped: local_parts mismatch
720 --------> real router <--------
721 local_part=a1 domain=myhost.test.ex
722 calling real router
723 real router called for a1@myhost.test.ex
724   domain = myhost.test.ex
725 queued for real transport: local_part = a1
726 domain = myhost.test.ex
727   errors_to=NULL
728   domain_data=NULL localpart_data=NULL
729 routed by real router
730   envelope to: a1@myhost.test.ex
731   transport: real
732 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
733 After routing:
734   Local deliveries:
735     a1@myhost.test.ex
736     b1@myhost.test.ex
737     c1@myhost.test.ex
738     d1@myhost.test.ex
739     d2@myhost.test.ex
740     d3@myhost.test.ex
741     e1@myhost.test.ex
742     f1@myhost.test.ex
743     f2@myhost.test.ex
744     f3@myhost.test.ex
745     a1@myhost.test.ex
746     b1@myhost.test.ex
747     c1@myhost.test.ex
748   Remote deliveries:
749     d1@myhost.test.ex
750     d2@myhost.test.ex
751     d3@myhost.test.ex
752     e1@myhost.test.ex
753     f1@myhost.test.ex
754     f2@myhost.test.ex
755     f3@myhost.test.ex
756   Failed addresses:
757   Deferred addresses:
758     g1@myhost.test.ex
759 locking TESTSUITE/spool/db/retry.lockfile
760 LOG: MAIN
761   => a1 <a1@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
762 locking TESTSUITE/spool/db/retry.lockfile
763 LOG: MAIN
764   => b1 <b1@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
765 locking TESTSUITE/spool/db/retry.lockfile
766 LOG: MAIN
767   => c1 <c1@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
768 locking TESTSUITE/spool/db/retry.lockfile
769 LOG: MAIN
770   => d1 <d1@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
771 locking TESTSUITE/spool/db/retry.lockfile
772 LOG: MAIN
773   => d2 <d2@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
774 locking TESTSUITE/spool/db/retry.lockfile
775 LOG: MAIN
776   => d3 <d3@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
777 locking TESTSUITE/spool/db/retry.lockfile
778 LOG: MAIN
779   => e1 <e1@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
780 locking TESTSUITE/spool/db/retry.lockfile
781 LOG: MAIN
782   => f1 <f1@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
783 locking TESTSUITE/spool/db/retry.lockfile
784 LOG: MAIN
785   => f2 <f2@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
786 locking TESTSUITE/spool/db/retry.lockfile
787 LOG: MAIN
788   => f3 <f3@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
789 locking TESTSUITE/spool/db/retry.lockfile
790 LOG: MAIN
791   => a1 <a1@myhost.test.ex> P=<> R=u1 T=ut1
792 log writing disabled
793 locking TESTSUITE/spool/db/retry.lockfile
794 transport error EPIPE ignored
795 LOG: MAIN
796   ** b1@myhost.test.ex P=<> 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
797 log writing disabled
798 locking TESTSUITE/spool/db/retry.lockfile
799 transport error EPIPE ignored
800 LOG: MAIN
801   == 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
802 log writing disabled
803 locking TESTSUITE/spool/db/retry.lockfile
804 locking TESTSUITE/spool/db/wait-ut4.lockfile
805 LOG: MAIN
806   => d1@myhost.test.ex P=<> R=ut4 T=ut4 H=127.0.0.1 [127.0.0.1] C="250 OK"
807 log writing disabled
808 locking TESTSUITE/spool/db/retry.lockfile
809 LOG: MAIN
810   == 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
811 log writing disabled
812 locking TESTSUITE/spool/db/retry.lockfile
813 locking TESTSUITE/spool/db/wait-ut4.lockfile
814 LOG: MAIN
815   ** d3@myhost.test.ex P=<> 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
816 log writing disabled
817 locking TESTSUITE/spool/db/retry.lockfile
818 locking TESTSUITE/spool/db/wait-ut5.lockfile
819 LOG: MAIN
820   ** e1@myhost.test.ex P=<> R=ut5 T=ut5 H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:<e1@myhost.test.ex>: 550 hard error
821 log writing disabled
822 locking TESTSUITE/spool/db/retry.lockfile
823 locking TESTSUITE/spool/db/wait-ut6.lockfile
824 LOG: MAIN
825   => f1@myhost.test.ex P=<CALLER@myhost.test.ex> R=ut6 T=ut6 H=127.0.0.1 [127.0.0.1] C="250 OK"
826 log writing disabled
827 locking TESTSUITE/spool/db/retry.lockfile
828 LOG: MAIN
829   == f2@myhost.test.ex R=ut6 T=ut6 defer (-44) H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:<f2@myhost.test.ex>: 450 soft error
830 log writing disabled
831 locking TESTSUITE/spool/db/retry.lockfile
832 locking TESTSUITE/spool/db/wait-ut6.lockfile
833 LOG: MAIN
834   ** f3@myhost.test.ex P=<CALLER@myhost.test.ex> R=ut6 T=ut6 H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:<f3@myhost.test.ex>: 550 hard error
835 log writing disabled
836 locking TESTSUITE/spool/db/retry.lockfile
837 LOG: MAIN
838   f3@myhost.test.ex <f3@myhost.test.ex>: error ignored
839 log writing disabled
840 Exim version x.yz ....
841 configuration file is TESTSUITE/test-config
842 trusted user
843 admin user
844 LOG: MAIN
845   <= <> R=10HmaX-0005vi-00 U=EXIMUSER P=local S=sss
846 Exim version x.yz ....
847 configuration file is TESTSUITE/test-config
848 trusted user
849 admin user
850 dropping to exim gid; retaining priv uid
851 locking TESTSUITE/spool/db/retry.lockfile
852 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
853 Considering: CALLER@myhost.test.ex
854 unique = CALLER@myhost.test.ex
855 CALLER@myhost.test.ex: queued for routing
856 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
857 routing CALLER@myhost.test.ex
858 --------> u1 router <--------
859 local_part=CALLER domain=myhost.test.ex
860 checking local_parts
861 u1 router skipped: local_parts mismatch
862 --------> ut2 router <--------
863 local_part=CALLER domain=myhost.test.ex
864 checking local_parts
865 ut2 router skipped: local_parts mismatch
866 --------> ut3 router <--------
867 local_part=CALLER domain=myhost.test.ex
868 checking local_parts
869 ut3 router skipped: local_parts mismatch
870 --------> ut4 router <--------
871 local_part=CALLER domain=myhost.test.ex
872 checking local_parts
873 ut4 router skipped: local_parts mismatch
874 --------> ut5 router <--------
875 local_part=CALLER domain=myhost.test.ex
876 checking local_parts
877 ut5 router skipped: local_parts mismatch
878 --------> ut6 router <--------
879 local_part=CALLER domain=myhost.test.ex
880 checking local_parts
881 ut6 router skipped: local_parts mismatch
882 --------> ut7 router <--------
883 local_part=CALLER domain=myhost.test.ex
884 checking local_parts
885 ut7 router skipped: local_parts mismatch
886 --------> ut8 router <--------
887 local_part=CALLER domain=myhost.test.ex
888 checking local_parts
889 ut8 router skipped: local_parts mismatch
890 --------> real router <--------
891 local_part=CALLER domain=myhost.test.ex
892 calling real router
893 real router called for CALLER@myhost.test.ex
894   domain = myhost.test.ex
895 set transport real
896 queued for real transport: local_part = CALLER
897 domain = myhost.test.ex
898   errors_to=NULL
899   domain_data=NULL localpart_data=NULL
900 routed by real router
901   envelope to: CALLER@myhost.test.ex
902   transport: real
903 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
904 After routing:
905   Local deliveries:
906     CALLER@myhost.test.ex
907   Remote deliveries:
908   Failed addresses:
909   Deferred addresses:
910 locking TESTSUITE/spool/db/retry.lockfile
911 LOG: MAIN
912   => CALLER <CALLER@myhost.test.ex> P=<> R=real T=real
913 LOG: MAIN
914   Completed
915 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
916 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
917 LOG: MAIN
918   d3@myhost.test.ex <d3@myhost.test.ex>: error ignored
919 log writing disabled
920 LOG: MAIN
921   b1@myhost.test.ex <b1@myhost.test.ex>: error ignored
922 log writing disabled
923 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
924 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
925 Exim version x.yz ....
926 configuration file is TESTSUITE/test-config
927 admin user
928 LOG: MAIN
929   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
930 Exim version x.yz ....
931 configuration file is TESTSUITE/test-config
932 trusted user
933 admin user
934 dropping to exim gid; retaining priv uid
935 locking TESTSUITE/spool/db/retry.lockfile
936 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
937 Considering: h1@myhost.test.ex
938 unique = h1@myhost.test.ex
939 h1@myhost.test.ex: queued for routing
940 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
941 routing h1@myhost.test.ex
942 --------> u1 router <--------
943 local_part=h1 domain=myhost.test.ex
944 checking local_parts
945 u1 router skipped: local_parts mismatch
946 --------> ut2 router <--------
947 local_part=h1 domain=myhost.test.ex
948 checking local_parts
949 ut2 router skipped: local_parts mismatch
950 --------> ut3 router <--------
951 local_part=h1 domain=myhost.test.ex
952 checking local_parts
953 ut3 router skipped: local_parts mismatch
954 --------> ut4 router <--------
955 local_part=h1 domain=myhost.test.ex
956 checking local_parts
957 ut4 router skipped: local_parts mismatch
958 --------> ut5 router <--------
959 local_part=h1 domain=myhost.test.ex
960 checking local_parts
961 ut5 router skipped: local_parts mismatch
962 --------> ut6 router <--------
963 local_part=h1 domain=myhost.test.ex
964 checking local_parts
965 ut6 router skipped: local_parts mismatch
966 --------> ut7 router <--------
967 local_part=h1 domain=myhost.test.ex
968 checking local_parts
969 ut7 router skipped: local_parts mismatch
970 --------> ut8 router <--------
971 local_part=h1 domain=myhost.test.ex
972 checking local_parts
973 calling ut8 router
974 ut8 router called for h1@myhost.test.ex
975   domain = myhost.test.ex
976 forced expansion failure - ignoring errors_to
977 set transport ut1
978 queued for ut1 transport: local_part = h1
979 domain = myhost.test.ex
980   errors_to=NULL
981   domain_data=NULL localpart_data=NULL
982 routed by ut8 router (unseen)
983   envelope to: h1@myhost.test.ex
984   transport: ut1
985 "unseen" set: replicated h1@myhost.test.ex
986 locking TESTSUITE/spool/db/retry.lockfile
987 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
988 Considering: h1@myhost.test.ex
989 unique = \0\h1@myhost.test.ex
990 h1@myhost.test.ex: queued for routing
991 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
992 routing h1@myhost.test.ex
993 --------> real router <--------
994 local_part=h1 domain=myhost.test.ex
995 calling real router
996 real router called for h1@myhost.test.ex
997   domain = myhost.test.ex
998 set transport real
999 queued for real transport: local_part = h1
1000 domain = myhost.test.ex
1001   errors_to=NULL
1002   domain_data=NULL localpart_data=NULL
1003 routed by real router
1004   envelope to: h1@myhost.test.ex
1005   transport: real
1006 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
1007 After routing:
1008   Local deliveries:
1009     h1@myhost.test.ex
1010     h1@myhost.test.ex
1011   Remote deliveries:
1012   Failed addresses:
1013   Deferred addresses:
1014 locking TESTSUITE/spool/db/retry.lockfile
1015 LOG: MAIN
1016   => h1 <h1@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
1017 locking TESTSUITE/spool/db/retry.lockfile
1018 LOG: MAIN
1019   => h1 <h1@myhost.test.ex> P=<CALLER@myhost.test.ex> R=ut8 T=ut1
1020 log writing disabled
1021 LOG: MAIN
1022   Completed
1023 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
1024 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>