DANE: do not check dns_again_means_nonexist for TLSA results of TRY_AGAIN
[exim.git] / test / stderr / 0183
1 Exim version x.yz ....
2 changed uid/gid: forcing real = effective
3   uid=uuuu gid=CALLER_GID pid=p1234
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"?
20  list element: ^srv
21  compiled caseless RE '^srv' not found in local cache
22  compiled RE '^srv' saved in local cache
23 userx in "^srv"? no (end of list)
24 srv router skipped: local_parts mismatch
25 --------> useryz router <--------
26 local_part=userx domain=test.again.dns
27 checking local_parts
28 userx in "usery:userz"?
29  list element: usery
30  list element: userz
31 userx in "usery:userz"? no (end of list)
32 useryz router skipped: local_parts mismatch
33 --------> lookuphost router <--------
34 local_part=userx domain=test.again.dns
35 checking local_parts
36 userx in "!userd"?
37  list element: !userd
38 userx in "!userd"? yes (end of list)
39 calling lookuphost router
40 lookuphost router called for userx@test.again.dns
41   domain = test.again.dns
42 test.again.dns in "*"?
43  list element: *
44  test.again.dns in "*"? yes (matched "*")
45 DNS lookup of test.again.dns (MX) using fakens
46 DNS lookup of test.again.dns (MX) gave TRY_AGAIN
47 test.again.dns in dns_again_means_nonexist? no (option unset)
48 returning DNS_AGAIN
49  writing neg-cache entry for test.again.dns-MX-xxxx, ttl -1
50 lookuphost router: defer for userx@test.again.dns
51   message: host lookup did not complete
52 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
53 Testing abcd@test.again.dns
54 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
55 Considering abcd@test.again.dns
56 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
57 routing abcd@test.again.dns
58 --------> srv router <--------
59 local_part=abcd domain=test.again.dns
60 checking local_parts
61 abcd in "^srv"?
62  list element: ^srv
63  compiled caseless RE '^srv' found in local cache
64 abcd in "^srv"? no (end of list)
65 srv router skipped: local_parts mismatch
66 --------> useryz router <--------
67 local_part=abcd domain=test.again.dns
68 checking local_parts
69 abcd in "usery:userz"?
70  list element: usery
71  list element: userz
72 abcd in "usery:userz"? no (end of list)
73 useryz router skipped: local_parts mismatch
74 --------> lookuphost router <--------
75 local_part=abcd domain=test.again.dns
76 checking local_parts
77 abcd in "!userd"?
78  list element: !userd
79 abcd in "!userd"? yes (end of list)
80 calling lookuphost router
81 lookuphost router called for abcd@test.again.dns
82   domain = test.again.dns
83 test.again.dns in "*"?
84  list element: *
85  test.again.dns in "*"? yes (matched "*")
86 DNS lookup of test.again.dns (MX): using cached value DNS_AGAIN
87 lookuphost router: defer for abcd@test.again.dns
88   message: host lookup did not complete
89 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
90 Testing abcd@ten-1.test.ex
91 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
92 Considering abcd@ten-1.test.ex
93 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
94 routing abcd@ten-1.test.ex
95 --------> srv router <--------
96 local_part=abcd domain=ten-1.test.ex
97 checking local_parts
98 abcd in "^srv"?
99  list element: ^srv
100  compiled caseless RE '^srv' found in local cache
101 abcd in "^srv"? no (end of list)
102 srv router skipped: local_parts mismatch
103 --------> useryz router <--------
104 local_part=abcd domain=ten-1.test.ex
105 checking local_parts
106 abcd in "usery:userz"?
107  list element: usery
108  list element: userz
109 abcd in "usery:userz"? no (end of list)
110 useryz router skipped: local_parts mismatch
111 --------> lookuphost router <--------
112 local_part=abcd domain=ten-1.test.ex
113 checking local_parts
114 abcd in "!userd"?
115  list element: !userd
116 abcd in "!userd"? yes (end of list)
117 calling lookuphost router
118 lookuphost router called for abcd@ten-1.test.ex
119   domain = ten-1.test.ex
120 ten-1.test.ex in "*"?
121  list element: *
122  ten-1.test.ex in "*"? yes (matched "*")
123 DNS lookup of ten-1.test.ex (MX) using fakens
124 DNS lookup of ten-1.test.ex (MX) gave NO_DATA
125 returning DNS_NODATA
126 faking res_search(MX) response length as 65535
127  writing neg-cache entry for ten-1.test.ex-MX-xxxx, ttl 3000
128 ten-1.test.ex (MX resp) DNSSEC
129  list element: *
130 DNS lookup of ten-1.test.ex (A) using fakens
131 DNS lookup of ten-1.test.ex (A) succeeded
132 fully qualified name = ten-1.test.ex
133 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx 
134 set transport smtp
135 queued for smtp transport: local_part = abcd
136 domain = ten-1.test.ex
137   errors_to=NULL
138   domain_data=NULL local_part_data=NULL
139 routed by lookuphost router
140   envelope to: abcd@ten-1.test.ex
141   transport: smtp
142   host ten-1.test.ex [V4NET.0.0.1]
143 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
144 Testing usery@test.again.dns
145 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
146 Considering usery@test.again.dns
147 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
148 routing usery@test.again.dns
149 --------> srv router <--------
150 local_part=usery domain=test.again.dns
151 checking local_parts
152 usery in "^srv"?
153  list element: ^srv
154  compiled caseless RE '^srv' found in local cache
155 usery in "^srv"? no (end of list)
156 srv router skipped: local_parts mismatch
157 --------> useryz router <--------
158 local_part=usery domain=test.again.dns
159 checking local_parts
160 usery in "usery:userz"?
161  list element: usery
162  usery in "usery:userz"? yes (matched "usery")
163 calling useryz router
164 useryz router called for usery@test.again.dns
165   domain = test.again.dns
166 route_item = * $domain bydns
167 test.again.dns in "*"?
168  list element: *
169  test.again.dns in "*"? yes (matched "*")
170 original list of hosts = '$domain' options = 'bydns'
171 expanded list of hosts = 'test.again.dns' options = 'bydns'
172 set transport smtp
173 finding IP address for test.again.dns
174 doing DNS lookup
175 test.again.dns in "*"?
176  list element: *
177  test.again.dns in "*"? yes (matched "*")
178  list element: *
179 DNS lookup of test.again.dns (A) using fakens
180 DNS lookup of test.again.dns (A) gave TRY_AGAIN
181 test.again.dns in dns_again_means_nonexist? no (option unset)
182 returning DNS_AGAIN
183  writing neg-cache entry for test.again.dns-A-xxxx, ttl -1
184 useryz router: defer for usery@test.again.dns
185   message: host lookup for test.again.dns did not complete (DNS timeout?)
186 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
187 Testing userz@test.again.dns
188 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
189 Considering userz@test.again.dns
190 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
191 routing userz@test.again.dns
192 --------> srv router <--------
193 local_part=userz domain=test.again.dns
194 checking local_parts
195 userz in "^srv"?
196  list element: ^srv
197  compiled caseless RE '^srv' found in local cache
198 userz in "^srv"? no (end of list)
199 srv router skipped: local_parts mismatch
200 --------> useryz router <--------
201 local_part=userz domain=test.again.dns
202 checking local_parts
203 userz in "usery:userz"?
204  list element: usery
205  list element: userz
206  userz in "usery:userz"? yes (matched "userz")
207 calling useryz router
208 useryz router called for userz@test.again.dns
209   domain = test.again.dns
210 route_item = * $domain bydns
211 test.again.dns in "*"?
212  list element: *
213  test.again.dns in "*"? yes (matched "*")
214 original list of hosts = '$domain' options = 'bydns'
215 expanded list of hosts = 'test.again.dns' options = 'bydns'
216 finding IP address for test.again.dns
217 doing DNS lookup
218 test.again.dns in "*"?
219  list element: *
220  test.again.dns in "*"? yes (matched "*")
221  list element: *
222 DNS lookup of test.again.dns (A): using cached value DNS_AGAIN
223 useryz router: defer for userz@test.again.dns
224   message: host lookup for test.again.dns did not complete (DNS timeout?)
225 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
226 Testing xyz@ten-1.test.ex
227 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
228 Considering xyz@ten-1.test.ex
229 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
230 routing xyz@ten-1.test.ex
231 --------> srv router <--------
232 local_part=xyz domain=ten-1.test.ex
233 checking local_parts
234 xyz in "^srv"?
235  list element: ^srv
236  compiled caseless RE '^srv' found in local cache
237 xyz in "^srv"? no (end of list)
238 srv router skipped: local_parts mismatch
239 --------> useryz router <--------
240 local_part=xyz domain=ten-1.test.ex
241 checking local_parts
242 xyz in "usery:userz"?
243  list element: usery
244  list element: userz
245 xyz in "usery:userz"? no (end of list)
246 useryz router skipped: local_parts mismatch
247 --------> lookuphost router <--------
248 local_part=xyz domain=ten-1.test.ex
249 checking local_parts
250 xyz in "!userd"?
251  list element: !userd
252 xyz in "!userd"? yes (end of list)
253 calling lookuphost router
254 lookuphost router called for xyz@ten-1.test.ex
255   domain = ten-1.test.ex
256 ten-1.test.ex in "*"?
257  list element: *
258  ten-1.test.ex in "*"? yes (matched "*")
259 DNS lookup of ten-1.test.ex (MX): using cached value DNS_NODATA
260  list element: *
261 DNS lookup of ten-1.test.ex (A) using fakens
262 DNS lookup of ten-1.test.ex (A) succeeded
263 fully qualified name = ten-1.test.ex
264 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx 
265 queued for smtp transport: local_part = xyz
266 domain = ten-1.test.ex
267   errors_to=NULL
268   domain_data=NULL local_part_data=NULL
269 routed by lookuphost router
270   envelope to: xyz@ten-1.test.ex
271   transport: smtp
272   host ten-1.test.ex [V4NET.0.0.1]
273 search_tidyup called
274 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=1 >>>>>>>>>>>>>>>>
275 Exim version x.yz ....
276 changed uid/gid: forcing real = effective
277   uid=uuuu gid=CALLER_GID pid=p1235
278 configuration file is TESTSUITE/test-config
279 admin user
280 dropping to exim gid; retaining priv uid
281 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
282 sender address = CALLER@myhost.test.ex
283 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
284 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
285 Testing userx@test.fail.dns
286 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
287 Considering userx@test.fail.dns
288 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
289 routing userx@test.fail.dns
290 --------> srv router <--------
291 local_part=userx domain=test.fail.dns
292 checking local_parts
293 userx in "^srv"?
294  list element: ^srv
295  compiled caseless RE '^srv' not found in local cache
296  compiled RE '^srv' saved in local cache
297 userx in "^srv"? no (end of list)
298 srv router skipped: local_parts mismatch
299 --------> useryz router <--------
300 local_part=userx domain=test.fail.dns
301 checking local_parts
302 userx in "usery:userz"?
303  list element: usery
304  list element: userz
305 userx in "usery:userz"? no (end of list)
306 useryz router skipped: local_parts mismatch
307 --------> lookuphost router <--------
308 local_part=userx domain=test.fail.dns
309 checking local_parts
310 userx in "!userd"?
311  list element: !userd
312 userx in "!userd"? yes (end of list)
313 calling lookuphost router
314 lookuphost router called for userx@test.fail.dns
315   domain = test.fail.dns
316 test.fail.dns in "*"?
317  list element: *
318  test.fail.dns in "*"? yes (matched "*")
319 DNS lookup of test.fail.dns (MX) using fakens
320 DNS lookup of test.fail.dns (MX) gave NO_RECOVERY
321 returning DNS_FAIL
322  writing neg-cache entry for test.fail.dns-MX-xxxx, ttl -1
323 lookuphost router: defer for userx@test.fail.dns
324   message: host lookup did not complete
325 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
326 Testing abcd@test.fail.dns
327 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
328 Considering abcd@test.fail.dns
329 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
330 routing abcd@test.fail.dns
331 --------> srv router <--------
332 local_part=abcd domain=test.fail.dns
333 checking local_parts
334 abcd in "^srv"?
335  list element: ^srv
336  compiled caseless RE '^srv' found in local cache
337 abcd in "^srv"? no (end of list)
338 srv router skipped: local_parts mismatch
339 --------> useryz router <--------
340 local_part=abcd domain=test.fail.dns
341 checking local_parts
342 abcd in "usery:userz"?
343  list element: usery
344  list element: userz
345 abcd in "usery:userz"? no (end of list)
346 useryz router skipped: local_parts mismatch
347 --------> lookuphost router <--------
348 local_part=abcd domain=test.fail.dns
349 checking local_parts
350 abcd in "!userd"?
351  list element: !userd
352 abcd in "!userd"? yes (end of list)
353 calling lookuphost router
354 lookuphost router called for abcd@test.fail.dns
355   domain = test.fail.dns
356 test.fail.dns in "*"?
357  list element: *
358  test.fail.dns in "*"? yes (matched "*")
359 DNS lookup of test.fail.dns (MX): using cached value DNS_FAIL
360 lookuphost router: defer for abcd@test.fail.dns
361   message: host lookup did not complete
362 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
363 Testing abcd@ten-1.test.ex
364 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
365 Considering abcd@ten-1.test.ex
366 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
367 routing abcd@ten-1.test.ex
368 --------> srv router <--------
369 local_part=abcd domain=ten-1.test.ex
370 checking local_parts
371 abcd in "^srv"?
372  list element: ^srv
373  compiled caseless RE '^srv' found in local cache
374 abcd in "^srv"? no (end of list)
375 srv router skipped: local_parts mismatch
376 --------> useryz router <--------
377 local_part=abcd domain=ten-1.test.ex
378 checking local_parts
379 abcd in "usery:userz"?
380  list element: usery
381  list element: userz
382 abcd in "usery:userz"? no (end of list)
383 useryz router skipped: local_parts mismatch
384 --------> lookuphost router <--------
385 local_part=abcd domain=ten-1.test.ex
386 checking local_parts
387 abcd in "!userd"?
388  list element: !userd
389 abcd in "!userd"? yes (end of list)
390 calling lookuphost router
391 lookuphost router called for abcd@ten-1.test.ex
392   domain = ten-1.test.ex
393 ten-1.test.ex in "*"?
394  list element: *
395  ten-1.test.ex in "*"? yes (matched "*")
396 DNS lookup of ten-1.test.ex (MX) using fakens
397 DNS lookup of ten-1.test.ex (MX) gave NO_DATA
398 returning DNS_NODATA
399 faking res_search(MX) response length as 65535
400  writing neg-cache entry for ten-1.test.ex-MX-xxxx, ttl 3000
401 ten-1.test.ex (MX resp) DNSSEC
402  list element: *
403 DNS lookup of ten-1.test.ex (A) using fakens
404 DNS lookup of ten-1.test.ex (A) succeeded
405 fully qualified name = ten-1.test.ex
406 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx 
407 set transport smtp
408 queued for smtp transport: local_part = abcd
409 domain = ten-1.test.ex
410   errors_to=NULL
411   domain_data=NULL local_part_data=NULL
412 routed by lookuphost router
413   envelope to: abcd@ten-1.test.ex
414   transport: smtp
415   host ten-1.test.ex [V4NET.0.0.1]
416 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
417 Testing usery@test.fail.dns
418 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
419 Considering usery@test.fail.dns
420 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
421 routing usery@test.fail.dns
422 --------> srv router <--------
423 local_part=usery domain=test.fail.dns
424 checking local_parts
425 usery in "^srv"?
426  list element: ^srv
427  compiled caseless RE '^srv' found in local cache
428 usery in "^srv"? no (end of list)
429 srv router skipped: local_parts mismatch
430 --------> useryz router <--------
431 local_part=usery domain=test.fail.dns
432 checking local_parts
433 usery in "usery:userz"?
434  list element: usery
435  usery in "usery:userz"? yes (matched "usery")
436 calling useryz router
437 useryz router called for usery@test.fail.dns
438   domain = test.fail.dns
439 route_item = * $domain bydns
440 test.fail.dns in "*"?
441  list element: *
442  test.fail.dns in "*"? yes (matched "*")
443 original list of hosts = '$domain' options = 'bydns'
444 expanded list of hosts = 'test.fail.dns' options = 'bydns'
445 set transport smtp
446 finding IP address for test.fail.dns
447 doing DNS lookup
448 test.fail.dns in "*"?
449  list element: *
450  test.fail.dns in "*"? yes (matched "*")
451  list element: *
452 DNS lookup of test.fail.dns (A) using fakens
453 DNS lookup of test.fail.dns (A) gave NO_RECOVERY
454 returning DNS_FAIL
455  writing neg-cache entry for test.fail.dns-A-xxxx, ttl -1
456 useryz router: defer for usery@test.fail.dns
457   message: host lookup for test.fail.dns did not complete (DNS timeout?)
458 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
459 Testing userz@test.fail.dns
460 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
461 Considering userz@test.fail.dns
462 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
463 routing userz@test.fail.dns
464 --------> srv router <--------
465 local_part=userz domain=test.fail.dns
466 checking local_parts
467 userz in "^srv"?
468  list element: ^srv
469  compiled caseless RE '^srv' found in local cache
470 userz in "^srv"? no (end of list)
471 srv router skipped: local_parts mismatch
472 --------> useryz router <--------
473 local_part=userz domain=test.fail.dns
474 checking local_parts
475 userz in "usery:userz"?
476  list element: usery
477  list element: userz
478  userz in "usery:userz"? yes (matched "userz")
479 calling useryz router
480 useryz router called for userz@test.fail.dns
481   domain = test.fail.dns
482 route_item = * $domain bydns
483 test.fail.dns in "*"?
484  list element: *
485  test.fail.dns in "*"? yes (matched "*")
486 original list of hosts = '$domain' options = 'bydns'
487 expanded list of hosts = 'test.fail.dns' options = 'bydns'
488 finding IP address for test.fail.dns
489 doing DNS lookup
490 test.fail.dns in "*"?
491  list element: *
492  test.fail.dns in "*"? yes (matched "*")
493  list element: *
494 DNS lookup of test.fail.dns (A): using cached value DNS_FAIL
495 useryz router: defer for userz@test.fail.dns
496   message: host lookup for test.fail.dns did not complete (DNS timeout?)
497 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
498 Testing xyz@ten-1.test.ex
499 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
500 Considering xyz@ten-1.test.ex
501 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
502 routing xyz@ten-1.test.ex
503 --------> srv router <--------
504 local_part=xyz domain=ten-1.test.ex
505 checking local_parts
506 xyz in "^srv"?
507  list element: ^srv
508  compiled caseless RE '^srv' found in local cache
509 xyz in "^srv"? no (end of list)
510 srv router skipped: local_parts mismatch
511 --------> useryz router <--------
512 local_part=xyz domain=ten-1.test.ex
513 checking local_parts
514 xyz in "usery:userz"?
515  list element: usery
516  list element: userz
517 xyz in "usery:userz"? no (end of list)
518 useryz router skipped: local_parts mismatch
519 --------> lookuphost router <--------
520 local_part=xyz domain=ten-1.test.ex
521 checking local_parts
522 xyz in "!userd"?
523  list element: !userd
524 xyz in "!userd"? yes (end of list)
525 calling lookuphost router
526 lookuphost router called for xyz@ten-1.test.ex
527   domain = ten-1.test.ex
528 ten-1.test.ex in "*"?
529  list element: *
530  ten-1.test.ex in "*"? yes (matched "*")
531 DNS lookup of ten-1.test.ex (MX): using cached value DNS_NODATA
532  list element: *
533 DNS lookup of ten-1.test.ex (A) using fakens
534 DNS lookup of ten-1.test.ex (A) succeeded
535 fully qualified name = ten-1.test.ex
536 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx 
537 queued for smtp transport: local_part = xyz
538 domain = ten-1.test.ex
539   errors_to=NULL
540   domain_data=NULL local_part_data=NULL
541 routed by lookuphost router
542   envelope to: xyz@ten-1.test.ex
543   transport: smtp
544   host ten-1.test.ex [V4NET.0.0.1]
545 search_tidyup called
546 >>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=1 >>>>>>>>>>>>>>>>
547 Exim version x.yz ....
548 changed uid/gid: forcing real = effective
549   uid=uuuu gid=CALLER_GID pid=p1236
550 configuration file is TESTSUITE/test-config
551 admin user
552 dropping to exim gid; retaining priv uid
553 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
554 sender address = CALLER@myhost.test.ex
555 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
556 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
557 Testing userx@nonexist.test.ex
558 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
559 Considering userx@nonexist.test.ex
560 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
561 routing userx@nonexist.test.ex
562 --------> srv router <--------
563 local_part=userx domain=nonexist.test.ex
564 checking local_parts
565 userx in "^srv"?
566  list element: ^srv
567  compiled caseless RE '^srv' not found in local cache
568  compiled RE '^srv' saved in local cache
569 userx in "^srv"? no (end of list)
570 srv router skipped: local_parts mismatch
571 --------> useryz router <--------
572 local_part=userx domain=nonexist.test.ex
573 checking local_parts
574 userx in "usery:userz"?
575  list element: usery
576  list element: userz
577 userx in "usery:userz"? no (end of list)
578 useryz router skipped: local_parts mismatch
579 --------> lookuphost router <--------
580 local_part=userx domain=nonexist.test.ex
581 checking local_parts
582 userx in "!userd"?
583  list element: !userd
584 userx in "!userd"? yes (end of list)
585 calling lookuphost router
586 lookuphost router called for userx@nonexist.test.ex
587   domain = nonexist.test.ex
588 nonexist.test.ex in "*"?
589  list element: *
590  nonexist.test.ex in "*"? yes (matched "*")
591 DNS lookup of nonexist.test.ex (MX) using fakens
592 DNS lookup of nonexist.test.ex (MX) gave HOST_NOT_FOUND
593 returning DNS_NOMATCH
594 faking res_search(MX) response length as 65535
595  writing neg-cache entry for nonexist.test.ex-MX-xxxx, ttl 3000
596 lookuphost router declined for userx@nonexist.test.ex
597 "more" is false: skipping remaining routers
598 no more routers
599 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
600 Testing abcd@nonexist.test.ex
601 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
602 Considering abcd@nonexist.test.ex
603 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
604 routing abcd@nonexist.test.ex
605 --------> srv router <--------
606 local_part=abcd domain=nonexist.test.ex
607 checking local_parts
608 abcd in "^srv"?
609  list element: ^srv
610  compiled caseless RE '^srv' found in local cache
611 abcd in "^srv"? no (end of list)
612 srv router skipped: local_parts mismatch
613 --------> useryz router <--------
614 local_part=abcd domain=nonexist.test.ex
615 checking local_parts
616 abcd in "usery:userz"?
617  list element: usery
618  list element: userz
619 abcd in "usery:userz"? no (end of list)
620 useryz router skipped: local_parts mismatch
621 --------> lookuphost router <--------
622 local_part=abcd domain=nonexist.test.ex
623 checking local_parts
624 abcd in "!userd"?
625  list element: !userd
626 abcd in "!userd"? yes (end of list)
627 calling lookuphost router
628 lookuphost router called for abcd@nonexist.test.ex
629   domain = nonexist.test.ex
630 nonexist.test.ex in "*"?
631  list element: *
632  nonexist.test.ex in "*"? yes (matched "*")
633 DNS lookup of nonexist.test.ex (MX): using cached value DNS_NOMATCH
634 lookuphost router declined for abcd@nonexist.test.ex
635 "more" is false: skipping remaining routers
636 no more routers
637 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
638 Testing abcd@ten-1.test.ex
639 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
640 Considering abcd@ten-1.test.ex
641 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
642 routing abcd@ten-1.test.ex
643 --------> srv router <--------
644 local_part=abcd domain=ten-1.test.ex
645 checking local_parts
646 abcd in "^srv"?
647  list element: ^srv
648  compiled caseless RE '^srv' found in local cache
649 abcd in "^srv"? no (end of list)
650 srv router skipped: local_parts mismatch
651 --------> useryz router <--------
652 local_part=abcd domain=ten-1.test.ex
653 checking local_parts
654 abcd in "usery:userz"?
655  list element: usery
656  list element: userz
657 abcd in "usery:userz"? no (end of list)
658 useryz router skipped: local_parts mismatch
659 --------> lookuphost router <--------
660 local_part=abcd domain=ten-1.test.ex
661 checking local_parts
662 abcd in "!userd"?
663  list element: !userd
664 abcd in "!userd"? yes (end of list)
665 calling lookuphost router
666 lookuphost router called for abcd@ten-1.test.ex
667   domain = ten-1.test.ex
668 ten-1.test.ex in "*"?
669  list element: *
670  ten-1.test.ex in "*"? yes (matched "*")
671 DNS lookup of ten-1.test.ex (MX) using fakens
672 DNS lookup of ten-1.test.ex (MX) gave NO_DATA
673 returning DNS_NODATA
674 faking res_search(MX) response length as 65535
675  writing neg-cache entry for ten-1.test.ex-MX-xxxx, ttl 3000
676 ten-1.test.ex (MX resp) DNSSEC
677  list element: *
678 DNS lookup of ten-1.test.ex (A) using fakens
679 DNS lookup of ten-1.test.ex (A) succeeded
680 fully qualified name = ten-1.test.ex
681 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx 
682 set transport smtp
683 queued for smtp transport: local_part = abcd
684 domain = ten-1.test.ex
685   errors_to=NULL
686   domain_data=NULL local_part_data=NULL
687 routed by lookuphost router
688   envelope to: abcd@ten-1.test.ex
689   transport: smtp
690   host ten-1.test.ex [V4NET.0.0.1]
691 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
692 Testing usery@nonexist.test.ex
693 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
694 Considering usery@nonexist.test.ex
695 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
696 routing usery@nonexist.test.ex
697 --------> srv router <--------
698 local_part=usery domain=nonexist.test.ex
699 checking local_parts
700 usery in "^srv"?
701  list element: ^srv
702  compiled caseless RE '^srv' found in local cache
703 usery in "^srv"? no (end of list)
704 srv router skipped: local_parts mismatch
705 --------> useryz router <--------
706 local_part=usery domain=nonexist.test.ex
707 checking local_parts
708 usery in "usery:userz"?
709  list element: usery
710  usery in "usery:userz"? yes (matched "usery")
711 calling useryz router
712 useryz router called for usery@nonexist.test.ex
713   domain = nonexist.test.ex
714 route_item = * $domain bydns
715 nonexist.test.ex in "*"?
716  list element: *
717  nonexist.test.ex in "*"? yes (matched "*")
718 original list of hosts = '$domain' options = 'bydns'
719 expanded list of hosts = 'nonexist.test.ex' options = 'bydns'
720 set transport smtp
721 finding IP address for nonexist.test.ex
722 doing DNS lookup
723 nonexist.test.ex in "*"?
724  list element: *
725  nonexist.test.ex in "*"? yes (matched "*")
726  list element: *
727 DNS lookup of nonexist.test.ex (A) using fakens
728 DNS lookup of nonexist.test.ex (A) gave HOST_NOT_FOUND
729 returning DNS_NOMATCH
730 faking res_search(A) response length as 65535
731  writing neg-cache entry for nonexist.test.ex-A-xxxx, ttl 3000
732 useryz router: defer for usery@nonexist.test.ex
733   message: lookup of host "nonexist.test.ex" failed in useryz router
734 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
735 Testing userz@nonexist.test.ex
736 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
737 Considering userz@nonexist.test.ex
738 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
739 routing userz@nonexist.test.ex
740 --------> srv router <--------
741 local_part=userz domain=nonexist.test.ex
742 checking local_parts
743 userz in "^srv"?
744  list element: ^srv
745  compiled caseless RE '^srv' found in local cache
746 userz in "^srv"? no (end of list)
747 srv router skipped: local_parts mismatch
748 --------> useryz router <--------
749 local_part=userz domain=nonexist.test.ex
750 checking local_parts
751 userz in "usery:userz"?
752  list element: usery
753  list element: userz
754  userz in "usery:userz"? yes (matched "userz")
755 calling useryz router
756 useryz router called for userz@nonexist.test.ex
757   domain = nonexist.test.ex
758 route_item = * $domain bydns
759 nonexist.test.ex in "*"?
760  list element: *
761  nonexist.test.ex in "*"? yes (matched "*")
762 original list of hosts = '$domain' options = 'bydns'
763 expanded list of hosts = 'nonexist.test.ex' options = 'bydns'
764 finding IP address for nonexist.test.ex
765 doing DNS lookup
766 nonexist.test.ex in "*"?
767  list element: *
768  nonexist.test.ex in "*"? yes (matched "*")
769  list element: *
770 DNS lookup of nonexist.test.ex (A): using cached value DNS_NOMATCH
771 useryz router: defer for userz@nonexist.test.ex
772   message: lookup of host "nonexist.test.ex" failed in useryz router
773 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
774 Testing xyz@ten-1.test.ex
775 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
776 Considering xyz@ten-1.test.ex
777 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
778 routing xyz@ten-1.test.ex
779 --------> srv router <--------
780 local_part=xyz domain=ten-1.test.ex
781 checking local_parts
782 xyz in "^srv"?
783  list element: ^srv
784  compiled caseless RE '^srv' found in local cache
785 xyz in "^srv"? no (end of list)
786 srv router skipped: local_parts mismatch
787 --------> useryz router <--------
788 local_part=xyz domain=ten-1.test.ex
789 checking local_parts
790 xyz in "usery:userz"?
791  list element: usery
792  list element: userz
793 xyz in "usery:userz"? no (end of list)
794 useryz router skipped: local_parts mismatch
795 --------> lookuphost router <--------
796 local_part=xyz domain=ten-1.test.ex
797 checking local_parts
798 xyz in "!userd"?
799  list element: !userd
800 xyz in "!userd"? yes (end of list)
801 calling lookuphost router
802 lookuphost router called for xyz@ten-1.test.ex
803   domain = ten-1.test.ex
804 ten-1.test.ex in "*"?
805  list element: *
806  ten-1.test.ex in "*"? yes (matched "*")
807 DNS lookup of ten-1.test.ex (MX): using cached value DNS_NODATA
808  list element: *
809 DNS lookup of ten-1.test.ex (A) using fakens
810 DNS lookup of ten-1.test.ex (A) succeeded
811 fully qualified name = ten-1.test.ex
812 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx 
813 queued for smtp transport: local_part = xyz
814 domain = ten-1.test.ex
815   errors_to=NULL
816   domain_data=NULL local_part_data=NULL
817 routed by lookuphost router
818   envelope to: xyz@ten-1.test.ex
819   transport: smtp
820   host ten-1.test.ex [V4NET.0.0.1]
821 search_tidyup called
822 >>>>>>>>>>>>>>>> Exim pid=p1236 (fresh-exec) terminating with rc=2 >>>>>>>>>>>>>>>>
823 Exim version x.yz ....
824 changed uid/gid: forcing real = effective
825   uid=uuuu gid=CALLER_GID pid=p1237
826 configuration file is TESTSUITE/test-config
827 admin user
828 dropping to exim gid; retaining priv uid
829 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
830 sender address = CALLER@myhost.test.ex
831 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
832 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
833 Testing srv@test.again.dns
834 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
835 Considering srv@test.again.dns
836 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
837 routing srv@test.again.dns
838 --------> srv router <--------
839 local_part=srv domain=test.again.dns
840 checking local_parts
841 srv in "^srv"?
842  list element: ^srv
843  compiled caseless RE '^srv' not found in local cache
844  compiled RE '^srv' saved in local cache
845  srv in "^srv"? yes (matched "^srv")
846 calling srv router
847 srv router called for srv@test.again.dns
848   domain = test.again.dns
849 test.again.dns in "*"?
850  list element: *
851  test.again.dns in "*"? yes (matched "*")
852 DNS lookup of _smtp._tcp.test.again.dns (SRV) using fakens
853 DNS lookup of _smtp._tcp.test.again.dns (SRV) gave TRY_AGAIN
854 _smtp._tcp.test.again.dns in dns_again_means_nonexist? no (option unset)
855 returning DNS_AGAIN
856  writing neg-cache entry for _smtp._tcp.test.again.dns-SRV-xxxx, ttl -1
857 test.again.dns in "test.fail.dns"?
858  list element: test.fail.dns
859 test.again.dns in "test.fail.dns"? no (end of list)
860 srv router: defer for srv@test.again.dns
861   message: host lookup did not complete
862 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
863 Testing srv@test.fail.dns
864 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
865 Considering srv@test.fail.dns
866 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
867 routing srv@test.fail.dns
868 --------> srv router <--------
869 local_part=srv domain=test.fail.dns
870 checking local_parts
871 srv in "^srv"?
872  list element: ^srv
873  compiled caseless RE '^srv' found in local cache
874  srv in "^srv"? yes (matched "^srv")
875 calling srv router
876 srv router called for srv@test.fail.dns
877   domain = test.fail.dns
878 test.fail.dns in "*"?
879  list element: *
880  test.fail.dns in "*"? yes (matched "*")
881 DNS lookup of _smtp._tcp.test.fail.dns (SRV) using fakens
882 DNS lookup of _smtp._tcp.test.fail.dns (SRV) gave NO_RECOVERY
883 returning DNS_FAIL
884  writing neg-cache entry for _smtp._tcp.test.fail.dns-SRV-xxxx, ttl -1
885 test.fail.dns in "test.fail.dns"?
886  list element: test.fail.dns
887  test.fail.dns in "test.fail.dns"? yes (matched "test.fail.dns")
888 DNS_FAIL treated as DNS_NODATA (domain in srv_fail_domains)
889 DNS lookup of test.fail.dns (MX) using fakens
890 DNS lookup of test.fail.dns (MX) gave NO_RECOVERY
891 returning DNS_FAIL
892  writing neg-cache entry for test.fail.dns-MX-xxxx, ttl -1
893 test.fail.dns in "test.fail.dns"?
894  list element: test.fail.dns
895  test.fail.dns in "test.fail.dns"? yes (matched "test.fail.dns")
896 DNS_FAIL treated as DNS_NODATA (domain in mx_fail_domains)
897  list element: *
898 DNS lookup of test.fail.dns (A) using fakens
899 DNS lookup of test.fail.dns (A) gave NO_RECOVERY
900 returning DNS_FAIL
901  writing neg-cache entry for test.fail.dns-A-xxxx, ttl -1
902 srv router: defer for srv@test.fail.dns
903   message: host lookup did not complete
904 search_tidyup called
905 >>>>>>>>>>>>>>>> Exim pid=p1237 (fresh-exec) terminating with rc=1 >>>>>>>>>>>>>>>>
906 Exim version x.yz ....
907 changed uid/gid: forcing real = effective
908   uid=uuuu gid=CALLER_GID pid=p1238
909 configuration file is TESTSUITE/test-config
910 admin user
911 dropping to exim gid; retaining priv uid
912 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
913 sender address = CALLER@myhost.test.ex
914 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
915 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
916 Testing userx@nonexist.example.com
917 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
918 Considering userx@nonexist.example.com
919 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
920 routing userx@nonexist.example.com
921 --------> srv router <--------
922 local_part=userx domain=nonexist.example.com
923 checking local_parts
924 userx in "^srv"?
925  list element: ^srv
926  compiled caseless RE '^srv' not found in local cache
927  compiled RE '^srv' saved in local cache
928 userx in "^srv"? no (end of list)
929 srv router skipped: local_parts mismatch
930 --------> useryz router <--------
931 local_part=userx domain=nonexist.example.com
932 checking local_parts
933 userx in "usery:userz"?
934  list element: usery
935  list element: userz
936 userx in "usery:userz"? no (end of list)
937 useryz router skipped: local_parts mismatch
938 --------> lookuphost router <--------
939 local_part=userx domain=nonexist.example.com
940 checking local_parts
941 userx in "!userd"?
942  list element: !userd
943 userx in "!userd"? yes (end of list)
944 calling lookuphost router
945 lookuphost router called for userx@nonexist.example.com
946   domain = nonexist.example.com
947 nonexist.example.com in "*"?
948  list element: *
949  nonexist.example.com in "*"? yes (matched "*")
950 DNS lookup of nonexist.example.com (MX) using fakens
951 DNS lookup of nonexist.example.com (MX) gave HOST_NOT_FOUND
952 returning DNS_NOMATCH
953 faking res_search(MX) response length as 65535
954  writing neg-cache entry for nonexist.example.com-MX-xxxx, ttl 2
955 lookuphost router declined for userx@nonexist.example.com
956 "more" is false: skipping remaining routers
957 no more routers
958 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
959 Testing userd@nonexist.example.com
960 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
961 Considering userd@nonexist.example.com
962 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
963 routing userd@nonexist.example.com
964 --------> srv router <--------
965 local_part=userd domain=nonexist.example.com
966 checking local_parts
967 userd in "^srv"?
968  list element: ^srv
969  compiled caseless RE '^srv' found in local cache
970 userd in "^srv"? no (end of list)
971 srv router skipped: local_parts mismatch
972 --------> useryz router <--------
973 local_part=userd domain=nonexist.example.com
974 checking local_parts
975 userd in "usery:userz"?
976  list element: usery
977  list element: userz
978 userd in "usery:userz"? no (end of list)
979 useryz router skipped: local_parts mismatch
980 --------> lookuphost router <--------
981 local_part=userd domain=nonexist.example.com
982 checking local_parts
983 userd in "!userd"?
984  list element: !userd
985  userd in "!userd"? no (matched "!userd")
986 lookuphost router skipped: local_parts mismatch
987 --------> delay router <--------
988 local_part=userd domain=nonexist.example.com
989 checking "condition" "${acl {delay}}"...
990   using ACL "delay"
991   processing "accept" (TESTSUITE/test-config 18)
992   check delay = 3s
993   delay modifier requests 3-second delay
994     message: y
995   accept: condition test succeeded in ACL "delay"
996   end of ACL "delay": ACCEPT
997 calling delay router
998 delay router called for userd@nonexist.example.com
999   domain = nonexist.example.com
1000 nonexist.example.com in "*"?
1001  list element: *
1002  nonexist.example.com in "*"? yes (matched "*")
1003 DNS lookup of nonexist.example.com (MX): cached value DNS_NOMATCH past valid time
1004 DNS lookup of nonexist.example.com (MX) using fakens
1005 DNS lookup of nonexist.example.com (MX) gave HOST_NOT_FOUND
1006 returning DNS_NOMATCH
1007 faking res_search(MX) response length as 65535
1008  update neg-cache entry for nonexist.example.com-MX-xxxx, ttl 2
1009 delay router declined for userd@nonexist.example.com
1010 "more" is false: skipping remaining routers
1011 no more routers
1012 search_tidyup called
1013 >>>>>>>>>>>>>>>> Exim pid=p1238 (fresh-exec) terminating with rc=2 >>>>>>>>>>>>>>>>