Consistent logging, always use DKIM instead of mixed DKIM/PDKIM
[users/jgh/exim.git] / test / stderr / 0183
1 Exim version x.yz ....
2 changed uid/gid: forcing real = effective
3   uid=uuuu gid=CALLER_GID pid=pppp
4 configuration file is TESTSUITE/test-config
5 admin user
6 dropping to exim gid; retaining priv uid
7 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
8 sender address = CALLER@myhost.test.ex
9 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
10 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
11 Testing userx@test.again.dns
12 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
13 Considering userx@test.again.dns
14 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
15 routing userx@test.again.dns
16 --------> srv router <--------
17 local_part=userx domain=test.again.dns
18 checking local_parts
19 userx in "^srv"? no (end of list)
20 srv router skipped: local_parts mismatch
21 --------> useryz router <--------
22 local_part=userx domain=test.again.dns
23 checking local_parts
24 userx in "usery:userz"? no (end of list)
25 useryz router skipped: local_parts mismatch
26 --------> lookuphost router <--------
27 local_part=userx domain=test.again.dns
28 checking local_parts
29 userx in "!userd"? yes (end of list)
30 calling lookuphost router
31 lookuphost router called for userx@test.again.dns
32   domain = test.again.dns
33 DNS lookup of test.again.dns (MX) using fakens
34 DNS lookup of test.again.dns (MX) gave TRY_AGAIN
35 test.again.dns in dns_again_means_nonexist? no (option unset)
36 returning DNS_AGAIN
37  writing neg-cache entry for test.again.dns-MX-xxxx, ttl -1
38 lookuphost router: defer for userx@test.again.dns
39   message: host lookup did not complete
40 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
41 Testing abcd@test.again.dns
42 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
43 Considering abcd@test.again.dns
44 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
45 routing abcd@test.again.dns
46 --------> srv router <--------
47 local_part=abcd domain=test.again.dns
48 checking local_parts
49 abcd in "^srv"? no (end of list)
50 srv router skipped: local_parts mismatch
51 --------> useryz router <--------
52 local_part=abcd domain=test.again.dns
53 checking local_parts
54 abcd in "usery:userz"? no (end of list)
55 useryz router skipped: local_parts mismatch
56 --------> lookuphost router <--------
57 local_part=abcd domain=test.again.dns
58 checking local_parts
59 abcd in "!userd"? yes (end of list)
60 calling lookuphost router
61 lookuphost router called for abcd@test.again.dns
62   domain = test.again.dns
63 DNS lookup of test.again.dns-MX: using cached value DNS_AGAIN
64 lookuphost router: defer for abcd@test.again.dns
65   message: host lookup did not complete
66 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
67 Testing abcd@ten-1.test.ex
68 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
69 Considering abcd@ten-1.test.ex
70 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
71 routing abcd@ten-1.test.ex
72 --------> srv router <--------
73 local_part=abcd domain=ten-1.test.ex
74 checking local_parts
75 abcd in "^srv"? no (end of list)
76 srv router skipped: local_parts mismatch
77 --------> useryz router <--------
78 local_part=abcd domain=ten-1.test.ex
79 checking local_parts
80 abcd in "usery:userz"? no (end of list)
81 useryz router skipped: local_parts mismatch
82 --------> lookuphost router <--------
83 local_part=abcd domain=ten-1.test.ex
84 checking local_parts
85 abcd in "!userd"? yes (end of list)
86 calling lookuphost router
87 lookuphost router called for abcd@ten-1.test.ex
88   domain = ten-1.test.ex
89 DNS lookup of ten-1.test.ex (MX) using fakens
90 DNS lookup of ten-1.test.ex (MX) gave NO_DATA
91 returning DNS_NODATA
92  writing neg-cache entry for ten-1.test.ex-MX-xxxx, ttl 3000
93 DNS lookup of ten-1.test.ex (A) using fakens
94 DNS lookup of ten-1.test.ex (A) succeeded
95 fully qualified name = ten-1.test.ex
96 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx 
97 set transport smtp
98 queued for smtp transport: local_part = abcd
99 domain = ten-1.test.ex
100   errors_to=NULL
101   domain_data=NULL localpart_data=NULL
102 routed by lookuphost router
103   envelope to: abcd@ten-1.test.ex
104   transport: smtp
105   host ten-1.test.ex [V4NET.0.0.1]
106 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
107 Testing usery@test.again.dns
108 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
109 Considering usery@test.again.dns
110 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
111 routing usery@test.again.dns
112 --------> srv router <--------
113 local_part=usery domain=test.again.dns
114 checking local_parts
115 usery in "^srv"? no (end of list)
116 srv router skipped: local_parts mismatch
117 --------> useryz router <--------
118 local_part=usery domain=test.again.dns
119 checking local_parts
120 usery in "usery:userz"? yes (matched "usery")
121 calling useryz router
122 useryz router called for usery@test.again.dns
123   domain = test.again.dns
124 route_item = * $domain bydns
125 test.again.dns in "*"? yes (matched "*")
126 original list of hosts = '$domain' options = 'bydns'
127 expanded list of hosts = 'test.again.dns' options = 'bydns'
128 set transport smtp
129 finding IP address for test.again.dns
130 doing DNS lookup
131 DNS lookup of test.again.dns (A) using fakens
132 DNS lookup of test.again.dns (A) gave TRY_AGAIN
133 test.again.dns in dns_again_means_nonexist? no (option unset)
134 returning DNS_AGAIN
135  writing neg-cache entry for test.again.dns-A-xxxx, ttl -1
136 useryz router: defer for usery@test.again.dns
137   message: host lookup for test.again.dns did not complete (DNS timeout?)
138 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
139 Testing userz@test.again.dns
140 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
141 Considering userz@test.again.dns
142 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
143 routing userz@test.again.dns
144 --------> srv router <--------
145 local_part=userz domain=test.again.dns
146 checking local_parts
147 userz in "^srv"? no (end of list)
148 srv router skipped: local_parts mismatch
149 --------> useryz router <--------
150 local_part=userz domain=test.again.dns
151 checking local_parts
152 userz in "usery:userz"? yes (matched "userz")
153 calling useryz router
154 useryz router called for userz@test.again.dns
155   domain = test.again.dns
156 route_item = * $domain bydns
157 test.again.dns in "*"? yes (matched "*")
158 original list of hosts = '$domain' options = 'bydns'
159 expanded list of hosts = 'test.again.dns' options = 'bydns'
160 finding IP address for test.again.dns
161 doing DNS lookup
162 DNS lookup of test.again.dns-A: using cached value DNS_AGAIN
163 useryz router: defer for userz@test.again.dns
164   message: host lookup for test.again.dns did not complete (DNS timeout?)
165 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
166 Testing xyz@ten-1.test.ex
167 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
168 Considering xyz@ten-1.test.ex
169 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
170 routing xyz@ten-1.test.ex
171 --------> srv router <--------
172 local_part=xyz domain=ten-1.test.ex
173 checking local_parts
174 xyz in "^srv"? no (end of list)
175 srv router skipped: local_parts mismatch
176 --------> useryz router <--------
177 local_part=xyz domain=ten-1.test.ex
178 checking local_parts
179 xyz in "usery:userz"? no (end of list)
180 useryz router skipped: local_parts mismatch
181 --------> lookuphost router <--------
182 local_part=xyz domain=ten-1.test.ex
183 checking local_parts
184 xyz in "!userd"? yes (end of list)
185 calling lookuphost router
186 lookuphost router called for xyz@ten-1.test.ex
187   domain = ten-1.test.ex
188 DNS lookup of ten-1.test.ex-MX: using cached value DNS_NODATA
189 DNS lookup of ten-1.test.ex (A) using fakens
190 DNS lookup of ten-1.test.ex (A) succeeded
191 fully qualified name = ten-1.test.ex
192 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx 
193 queued for smtp transport: local_part = xyz
194 domain = ten-1.test.ex
195   errors_to=NULL
196   domain_data=NULL localpart_data=NULL
197 routed by lookuphost router
198   envelope to: xyz@ten-1.test.ex
199   transport: smtp
200   host ten-1.test.ex [V4NET.0.0.1]
201 search_tidyup called
202 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=1 >>>>>>>>>>>>>>>>
203 Exim version x.yz ....
204 changed uid/gid: forcing real = effective
205   uid=uuuu gid=CALLER_GID pid=pppp
206 configuration file is TESTSUITE/test-config
207 admin user
208 dropping to exim gid; retaining priv uid
209 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
210 sender address = CALLER@myhost.test.ex
211 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
212 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
213 Testing userx@test.fail.dns
214 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
215 Considering userx@test.fail.dns
216 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
217 routing userx@test.fail.dns
218 --------> srv router <--------
219 local_part=userx domain=test.fail.dns
220 checking local_parts
221 userx in "^srv"? no (end of list)
222 srv router skipped: local_parts mismatch
223 --------> useryz router <--------
224 local_part=userx domain=test.fail.dns
225 checking local_parts
226 userx in "usery:userz"? no (end of list)
227 useryz router skipped: local_parts mismatch
228 --------> lookuphost router <--------
229 local_part=userx domain=test.fail.dns
230 checking local_parts
231 userx in "!userd"? yes (end of list)
232 calling lookuphost router
233 lookuphost router called for userx@test.fail.dns
234   domain = test.fail.dns
235 DNS lookup of test.fail.dns (MX) using fakens
236 DNS lookup of test.fail.dns (MX) gave NO_RECOVERY
237 returning DNS_FAIL
238  writing neg-cache entry for test.fail.dns-MX-xxxx, ttl -1
239 lookuphost router: defer for userx@test.fail.dns
240   message: host lookup did not complete
241 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
242 Testing abcd@test.fail.dns
243 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
244 Considering abcd@test.fail.dns
245 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
246 routing abcd@test.fail.dns
247 --------> srv router <--------
248 local_part=abcd domain=test.fail.dns
249 checking local_parts
250 abcd in "^srv"? no (end of list)
251 srv router skipped: local_parts mismatch
252 --------> useryz router <--------
253 local_part=abcd domain=test.fail.dns
254 checking local_parts
255 abcd in "usery:userz"? no (end of list)
256 useryz router skipped: local_parts mismatch
257 --------> lookuphost router <--------
258 local_part=abcd domain=test.fail.dns
259 checking local_parts
260 abcd in "!userd"? yes (end of list)
261 calling lookuphost router
262 lookuphost router called for abcd@test.fail.dns
263   domain = test.fail.dns
264 DNS lookup of test.fail.dns-MX: using cached value DNS_FAIL
265 lookuphost router: defer for abcd@test.fail.dns
266   message: host lookup did not complete
267 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
268 Testing abcd@ten-1.test.ex
269 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
270 Considering abcd@ten-1.test.ex
271 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
272 routing abcd@ten-1.test.ex
273 --------> srv router <--------
274 local_part=abcd domain=ten-1.test.ex
275 checking local_parts
276 abcd in "^srv"? no (end of list)
277 srv router skipped: local_parts mismatch
278 --------> useryz router <--------
279 local_part=abcd domain=ten-1.test.ex
280 checking local_parts
281 abcd in "usery:userz"? no (end of list)
282 useryz router skipped: local_parts mismatch
283 --------> lookuphost router <--------
284 local_part=abcd domain=ten-1.test.ex
285 checking local_parts
286 abcd in "!userd"? yes (end of list)
287 calling lookuphost router
288 lookuphost router called for abcd@ten-1.test.ex
289   domain = ten-1.test.ex
290 DNS lookup of ten-1.test.ex (MX) using fakens
291 DNS lookup of ten-1.test.ex (MX) gave NO_DATA
292 returning DNS_NODATA
293  writing neg-cache entry for ten-1.test.ex-MX-xxxx, ttl 3000
294 DNS lookup of ten-1.test.ex (A) using fakens
295 DNS lookup of ten-1.test.ex (A) succeeded
296 fully qualified name = ten-1.test.ex
297 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx 
298 set transport smtp
299 queued for smtp transport: local_part = abcd
300 domain = ten-1.test.ex
301   errors_to=NULL
302   domain_data=NULL localpart_data=NULL
303 routed by lookuphost router
304   envelope to: abcd@ten-1.test.ex
305   transport: smtp
306   host ten-1.test.ex [V4NET.0.0.1]
307 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
308 Testing usery@test.fail.dns
309 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
310 Considering usery@test.fail.dns
311 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
312 routing usery@test.fail.dns
313 --------> srv router <--------
314 local_part=usery domain=test.fail.dns
315 checking local_parts
316 usery in "^srv"? no (end of list)
317 srv router skipped: local_parts mismatch
318 --------> useryz router <--------
319 local_part=usery domain=test.fail.dns
320 checking local_parts
321 usery in "usery:userz"? yes (matched "usery")
322 calling useryz router
323 useryz router called for usery@test.fail.dns
324   domain = test.fail.dns
325 route_item = * $domain bydns
326 test.fail.dns in "*"? yes (matched "*")
327 original list of hosts = '$domain' options = 'bydns'
328 expanded list of hosts = 'test.fail.dns' options = 'bydns'
329 set transport smtp
330 finding IP address for test.fail.dns
331 doing DNS lookup
332 DNS lookup of test.fail.dns (A) using fakens
333 DNS lookup of test.fail.dns (A) gave NO_RECOVERY
334 returning DNS_FAIL
335  writing neg-cache entry for test.fail.dns-A-xxxx, ttl -1
336 useryz router: defer for usery@test.fail.dns
337   message: host lookup for test.fail.dns did not complete (DNS timeout?)
338 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
339 Testing userz@test.fail.dns
340 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
341 Considering userz@test.fail.dns
342 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
343 routing userz@test.fail.dns
344 --------> srv router <--------
345 local_part=userz domain=test.fail.dns
346 checking local_parts
347 userz in "^srv"? no (end of list)
348 srv router skipped: local_parts mismatch
349 --------> useryz router <--------
350 local_part=userz domain=test.fail.dns
351 checking local_parts
352 userz in "usery:userz"? yes (matched "userz")
353 calling useryz router
354 useryz router called for userz@test.fail.dns
355   domain = test.fail.dns
356 route_item = * $domain bydns
357 test.fail.dns in "*"? yes (matched "*")
358 original list of hosts = '$domain' options = 'bydns'
359 expanded list of hosts = 'test.fail.dns' options = 'bydns'
360 finding IP address for test.fail.dns
361 doing DNS lookup
362 DNS lookup of test.fail.dns-A: using cached value DNS_FAIL
363 useryz router: defer for userz@test.fail.dns
364   message: host lookup for test.fail.dns did not complete (DNS timeout?)
365 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
366 Testing xyz@ten-1.test.ex
367 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
368 Considering xyz@ten-1.test.ex
369 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
370 routing xyz@ten-1.test.ex
371 --------> srv router <--------
372 local_part=xyz domain=ten-1.test.ex
373 checking local_parts
374 xyz in "^srv"? no (end of list)
375 srv router skipped: local_parts mismatch
376 --------> useryz router <--------
377 local_part=xyz domain=ten-1.test.ex
378 checking local_parts
379 xyz in "usery:userz"? no (end of list)
380 useryz router skipped: local_parts mismatch
381 --------> lookuphost router <--------
382 local_part=xyz domain=ten-1.test.ex
383 checking local_parts
384 xyz in "!userd"? yes (end of list)
385 calling lookuphost router
386 lookuphost router called for xyz@ten-1.test.ex
387   domain = ten-1.test.ex
388 DNS lookup of ten-1.test.ex-MX: using cached value DNS_NODATA
389 DNS lookup of ten-1.test.ex (A) using fakens
390 DNS lookup of ten-1.test.ex (A) succeeded
391 fully qualified name = ten-1.test.ex
392 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx 
393 queued for smtp transport: local_part = xyz
394 domain = ten-1.test.ex
395   errors_to=NULL
396   domain_data=NULL localpart_data=NULL
397 routed by lookuphost router
398   envelope to: xyz@ten-1.test.ex
399   transport: smtp
400   host ten-1.test.ex [V4NET.0.0.1]
401 search_tidyup called
402 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=1 >>>>>>>>>>>>>>>>
403 Exim version x.yz ....
404 changed uid/gid: forcing real = effective
405   uid=uuuu gid=CALLER_GID pid=pppp
406 configuration file is TESTSUITE/test-config
407 admin user
408 dropping to exim gid; retaining priv uid
409 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
410 sender address = CALLER@myhost.test.ex
411 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
412 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
413 Testing userx@nonexist.test.ex
414 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
415 Considering userx@nonexist.test.ex
416 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
417 routing userx@nonexist.test.ex
418 --------> srv router <--------
419 local_part=userx domain=nonexist.test.ex
420 checking local_parts
421 userx in "^srv"? no (end of list)
422 srv router skipped: local_parts mismatch
423 --------> useryz router <--------
424 local_part=userx domain=nonexist.test.ex
425 checking local_parts
426 userx in "usery:userz"? no (end of list)
427 useryz router skipped: local_parts mismatch
428 --------> lookuphost router <--------
429 local_part=userx domain=nonexist.test.ex
430 checking local_parts
431 userx in "!userd"? yes (end of list)
432 calling lookuphost router
433 lookuphost router called for userx@nonexist.test.ex
434   domain = nonexist.test.ex
435 DNS lookup of nonexist.test.ex (MX) using fakens
436 DNS lookup of nonexist.test.ex (MX) gave HOST_NOT_FOUND
437 returning DNS_NOMATCH
438  writing neg-cache entry for nonexist.test.ex-MX-xxxx, ttl 3000
439 lookuphost router declined for userx@nonexist.test.ex
440 "more" is false: skipping remaining routers
441 no more routers
442 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
443 Testing abcd@nonexist.test.ex
444 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
445 Considering abcd@nonexist.test.ex
446 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
447 routing abcd@nonexist.test.ex
448 --------> srv router <--------
449 local_part=abcd domain=nonexist.test.ex
450 checking local_parts
451 abcd in "^srv"? no (end of list)
452 srv router skipped: local_parts mismatch
453 --------> useryz router <--------
454 local_part=abcd domain=nonexist.test.ex
455 checking local_parts
456 abcd in "usery:userz"? no (end of list)
457 useryz router skipped: local_parts mismatch
458 --------> lookuphost router <--------
459 local_part=abcd domain=nonexist.test.ex
460 checking local_parts
461 abcd in "!userd"? yes (end of list)
462 calling lookuphost router
463 lookuphost router called for abcd@nonexist.test.ex
464   domain = nonexist.test.ex
465 DNS lookup of nonexist.test.ex-MX: using cached value DNS_NOMATCH
466 lookuphost router declined for abcd@nonexist.test.ex
467 "more" is false: skipping remaining routers
468 no more routers
469 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
470 Testing abcd@ten-1.test.ex
471 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
472 Considering abcd@ten-1.test.ex
473 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
474 routing abcd@ten-1.test.ex
475 --------> srv router <--------
476 local_part=abcd domain=ten-1.test.ex
477 checking local_parts
478 abcd in "^srv"? no (end of list)
479 srv router skipped: local_parts mismatch
480 --------> useryz router <--------
481 local_part=abcd domain=ten-1.test.ex
482 checking local_parts
483 abcd in "usery:userz"? no (end of list)
484 useryz router skipped: local_parts mismatch
485 --------> lookuphost router <--------
486 local_part=abcd domain=ten-1.test.ex
487 checking local_parts
488 abcd in "!userd"? yes (end of list)
489 calling lookuphost router
490 lookuphost router called for abcd@ten-1.test.ex
491   domain = ten-1.test.ex
492 DNS lookup of ten-1.test.ex (MX) using fakens
493 DNS lookup of ten-1.test.ex (MX) gave NO_DATA
494 returning DNS_NODATA
495  writing neg-cache entry for ten-1.test.ex-MX-xxxx, ttl 3000
496 DNS lookup of ten-1.test.ex (A) using fakens
497 DNS lookup of ten-1.test.ex (A) succeeded
498 fully qualified name = ten-1.test.ex
499 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx 
500 set transport smtp
501 queued for smtp transport: local_part = abcd
502 domain = ten-1.test.ex
503   errors_to=NULL
504   domain_data=NULL localpart_data=NULL
505 routed by lookuphost router
506   envelope to: abcd@ten-1.test.ex
507   transport: smtp
508   host ten-1.test.ex [V4NET.0.0.1]
509 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
510 Testing usery@nonexist.test.ex
511 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
512 Considering usery@nonexist.test.ex
513 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
514 routing usery@nonexist.test.ex
515 --------> srv router <--------
516 local_part=usery domain=nonexist.test.ex
517 checking local_parts
518 usery in "^srv"? no (end of list)
519 srv router skipped: local_parts mismatch
520 --------> useryz router <--------
521 local_part=usery domain=nonexist.test.ex
522 checking local_parts
523 usery in "usery:userz"? yes (matched "usery")
524 calling useryz router
525 useryz router called for usery@nonexist.test.ex
526   domain = nonexist.test.ex
527 route_item = * $domain bydns
528 nonexist.test.ex in "*"? yes (matched "*")
529 original list of hosts = '$domain' options = 'bydns'
530 expanded list of hosts = 'nonexist.test.ex' options = 'bydns'
531 set transport smtp
532 finding IP address for nonexist.test.ex
533 doing DNS lookup
534 DNS lookup of nonexist.test.ex (A) using fakens
535 DNS lookup of nonexist.test.ex (A) gave HOST_NOT_FOUND
536 returning DNS_NOMATCH
537  writing neg-cache entry for nonexist.test.ex-A-xxxx, ttl 3000
538 useryz router: defer for usery@nonexist.test.ex
539   message: lookup of host "nonexist.test.ex" failed in useryz router
540 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
541 Testing userz@nonexist.test.ex
542 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
543 Considering userz@nonexist.test.ex
544 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
545 routing userz@nonexist.test.ex
546 --------> srv router <--------
547 local_part=userz domain=nonexist.test.ex
548 checking local_parts
549 userz in "^srv"? no (end of list)
550 srv router skipped: local_parts mismatch
551 --------> useryz router <--------
552 local_part=userz domain=nonexist.test.ex
553 checking local_parts
554 userz in "usery:userz"? yes (matched "userz")
555 calling useryz router
556 useryz router called for userz@nonexist.test.ex
557   domain = nonexist.test.ex
558 route_item = * $domain bydns
559 nonexist.test.ex in "*"? yes (matched "*")
560 original list of hosts = '$domain' options = 'bydns'
561 expanded list of hosts = 'nonexist.test.ex' options = 'bydns'
562 finding IP address for nonexist.test.ex
563 doing DNS lookup
564 DNS lookup of nonexist.test.ex-A: using cached value DNS_NOMATCH
565 useryz router: defer for userz@nonexist.test.ex
566   message: lookup of host "nonexist.test.ex" failed in useryz router
567 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
568 Testing xyz@ten-1.test.ex
569 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
570 Considering xyz@ten-1.test.ex
571 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
572 routing xyz@ten-1.test.ex
573 --------> srv router <--------
574 local_part=xyz domain=ten-1.test.ex
575 checking local_parts
576 xyz in "^srv"? no (end of list)
577 srv router skipped: local_parts mismatch
578 --------> useryz router <--------
579 local_part=xyz domain=ten-1.test.ex
580 checking local_parts
581 xyz in "usery:userz"? no (end of list)
582 useryz router skipped: local_parts mismatch
583 --------> lookuphost router <--------
584 local_part=xyz domain=ten-1.test.ex
585 checking local_parts
586 xyz in "!userd"? yes (end of list)
587 calling lookuphost router
588 lookuphost router called for xyz@ten-1.test.ex
589   domain = ten-1.test.ex
590 DNS lookup of ten-1.test.ex-MX: using cached value DNS_NODATA
591 DNS lookup of ten-1.test.ex (A) using fakens
592 DNS lookup of ten-1.test.ex (A) succeeded
593 fully qualified name = ten-1.test.ex
594 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx 
595 queued for smtp transport: local_part = xyz
596 domain = ten-1.test.ex
597   errors_to=NULL
598   domain_data=NULL localpart_data=NULL
599 routed by lookuphost router
600   envelope to: xyz@ten-1.test.ex
601   transport: smtp
602   host ten-1.test.ex [V4NET.0.0.1]
603 search_tidyup called
604 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=2 >>>>>>>>>>>>>>>>
605 Exim version x.yz ....
606 changed uid/gid: forcing real = effective
607   uid=uuuu gid=CALLER_GID pid=pppp
608 configuration file is TESTSUITE/test-config
609 admin user
610 dropping to exim gid; retaining priv uid
611 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
612 sender address = CALLER@myhost.test.ex
613 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
614 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
615 Testing srv@test.again.dns
616 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
617 Considering srv@test.again.dns
618 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
619 routing srv@test.again.dns
620 --------> srv router <--------
621 local_part=srv domain=test.again.dns
622 checking local_parts
623 srv in "^srv"? yes (matched "^srv")
624 calling srv router
625 srv router called for srv@test.again.dns
626   domain = test.again.dns
627 DNS lookup of _smtp._tcp.test.again.dns (SRV) using fakens
628 DNS lookup of _smtp._tcp.test.again.dns (SRV) gave TRY_AGAIN
629 _smtp._tcp.test.again.dns in dns_again_means_nonexist? no (option unset)
630 returning DNS_AGAIN
631  writing neg-cache entry for _smtp._tcp.test.again.dns-SRV-xxxx, ttl -1
632 test.again.dns in "test.fail.dns"? no (end of list)
633 srv router: defer for srv@test.again.dns
634   message: host lookup did not complete
635 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
636 Testing srv@test.fail.dns
637 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
638 Considering srv@test.fail.dns
639 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
640 routing srv@test.fail.dns
641 --------> srv router <--------
642 local_part=srv domain=test.fail.dns
643 checking local_parts
644 srv in "^srv"? yes (matched "^srv")
645 calling srv router
646 srv router called for srv@test.fail.dns
647   domain = test.fail.dns
648 DNS lookup of _smtp._tcp.test.fail.dns (SRV) using fakens
649 DNS lookup of _smtp._tcp.test.fail.dns (SRV) gave NO_RECOVERY
650 returning DNS_FAIL
651  writing neg-cache entry for _smtp._tcp.test.fail.dns-SRV-xxxx, ttl -1
652 test.fail.dns in "test.fail.dns"? yes (matched "test.fail.dns")
653 DNS_FAIL treated as DNS_NODATA (domain in srv_fail_domains)
654 DNS lookup of test.fail.dns (MX) using fakens
655 DNS lookup of test.fail.dns (MX) gave NO_RECOVERY
656 returning DNS_FAIL
657  writing neg-cache entry for test.fail.dns-MX-xxxx, ttl -1
658 test.fail.dns in "test.fail.dns"? yes (matched "test.fail.dns")
659 DNS_FAIL treated as DNS_NODATA (domain in mx_fail_domains)
660 DNS lookup of test.fail.dns (A) using fakens
661 DNS lookup of test.fail.dns (A) gave NO_RECOVERY
662 returning DNS_FAIL
663  writing neg-cache entry for test.fail.dns-A-xxxx, ttl -1
664 srv router: defer for srv@test.fail.dns
665   message: host lookup did not complete
666 search_tidyup called
667 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=1 >>>>>>>>>>>>>>>>
668 Exim version x.yz ....
669 changed uid/gid: forcing real = effective
670   uid=uuuu gid=CALLER_GID pid=pppp
671 configuration file is TESTSUITE/test-config
672 admin user
673 dropping to exim gid; retaining priv uid
674 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
675 sender address = CALLER@myhost.test.ex
676 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
677 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
678 Testing userx@nonexist.example.com
679 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
680 Considering userx@nonexist.example.com
681 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
682 routing userx@nonexist.example.com
683 --------> srv router <--------
684 local_part=userx domain=nonexist.example.com
685 checking local_parts
686 userx in "^srv"? no (end of list)
687 srv router skipped: local_parts mismatch
688 --------> useryz router <--------
689 local_part=userx domain=nonexist.example.com
690 checking local_parts
691 userx in "usery:userz"? no (end of list)
692 useryz router skipped: local_parts mismatch
693 --------> lookuphost router <--------
694 local_part=userx domain=nonexist.example.com
695 checking local_parts
696 userx in "!userd"? yes (end of list)
697 calling lookuphost router
698 lookuphost router called for userx@nonexist.example.com
699   domain = nonexist.example.com
700 DNS lookup of nonexist.example.com (MX) using fakens
701 DNS lookup of nonexist.example.com (MX) gave HOST_NOT_FOUND
702 returning DNS_NOMATCH
703  writing neg-cache entry for nonexist.example.com-MX-xxxx, ttl 2
704 lookuphost router declined for userx@nonexist.example.com
705 "more" is false: skipping remaining routers
706 no more routers
707 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
708 Testing userd@nonexist.example.com
709 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
710 Considering userd@nonexist.example.com
711 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
712 routing userd@nonexist.example.com
713 --------> srv router <--------
714 local_part=userd domain=nonexist.example.com
715 checking local_parts
716 userd in "^srv"? no (end of list)
717 srv router skipped: local_parts mismatch
718 --------> useryz router <--------
719 local_part=userd domain=nonexist.example.com
720 checking local_parts
721 userd in "usery:userz"? no (end of list)
722 useryz router skipped: local_parts mismatch
723 --------> lookuphost router <--------
724 local_part=userd domain=nonexist.example.com
725 checking local_parts
726 userd in "!userd"? no (matched "!userd")
727 lookuphost router skipped: local_parts mismatch
728 --------> delay router <--------
729 local_part=userd domain=nonexist.example.com
730 checking "condition" "${acl {delay}}"...
731   using ACL "delay"
732   processing "accept" (TESTSUITE/test-config 18)
733   check delay = 3s
734   delay modifier requests 3-second delay
735     message: y
736   accept: condition test succeeded in ACL "delay"
737   end of ACL "delay": ACCEPT
738 calling delay router
739 delay router called for userd@nonexist.example.com
740   domain = nonexist.example.com
741 DNS lookup of nonexist.example.com-MX: cached value DNS_NOMATCH past valid time
742 DNS lookup of nonexist.example.com (MX) using fakens
743 DNS lookup of nonexist.example.com (MX) gave HOST_NOT_FOUND
744 returning DNS_NOMATCH
745  update neg-cache entry for nonexist.example.com-MX-xxxx, ttl 2
746 delay router declined for userd@nonexist.example.com
747 "more" is false: skipping remaining routers
748 no more routers
749 search_tidyup called
750 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=2 >>>>>>>>>>>>>>>>