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