test/stderr/0183

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