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