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