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